6:26 a.m.
See
http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=java15_default,lab...
Changes:
[tom.baeyens(a)jboss.com] JBPM-2147 fixing the integration tests
------------------------------------------
[...truncated 5229 lines...]
at junit.framework.TestCase.runTest(TestCase.java:154)
at org.jbpm.test.BaseJbpmTestCase.runTest(BaseJbpmTestCase.java:81)
at junit.framework.TestCase.runBare(TestCase.java:127)
at junit.framework.TestResult$1.protect(TestResult.java:106)
at junit.framework.TestResult.runProtected(TestResult.java:124)
at junit.framework.TestResult.run(TestResult.java:109)
at junit.framework.TestCase.run(TestCase.java:118)
at junit.framework.TestSuite.runTest(TestSuite.java:208)
at junit.framework.TestSuite.run(TestSuite.java:203)
at sun.reflect.GeneratedMethodAccessor6.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.apache.maven.surefire.junit.JUnitTestSet.execute(JUnitTestSet.java:213)
at
org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet(AbstractDirectoryTestSuite.java:140)
at
org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute(AbstractDirectoryTestSuite.java:127)
at org.apache.maven.surefire.Surefire.run(Surefire.java:177)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at
org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:338)
at org.apache.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:997)
Caused by: org.jbpm.api.JbpmException: couldn't invoke 'bark' with null on
org.jbpm.test.mgmt.JobQueryTest$Dog@1e75713: wooof
at org.jbpm.pvm.internal.util.ReflectUtil.invoke(ReflectUtil.java:270)
at org.jbpm.jpdl.internal.activity.JavaActivity.perform(JavaActivity.java:88)
... 44 more
Caused by: java.lang.RuntimeException: wooof
at org.jbpm.test.mgmt.JobQueryTest$Dog.bark(JobQueryTest.java:62)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jbpm.pvm.internal.util.ReflectUtil.invoke(ReflectUtil.java:267)
... 45 more
### EXCEPTION ###########################################
07:25:39,688 FIN | [JobExceptionHandler] starting new transaction for handling job
exception
07:25:39,694 FIN | [JobExceptionHandler] handling job 18 exception: couldn't invoke
method bark: couldn't invoke 'bark' with null on
org.jbpm.test.mgmt.JobQueryTest$Dog@1e75713: wooof
07:25:39,700 FIN | [JobExceptionHandler] decrementing retries to 0 for
ExecuteActivityMessage[18]
07:25:39,707 FIN | [JobExceptionHandler] completed transaction for handling job exception
07:25:39,730 FIN | [DbSessionImpl] deleting process instance ErrorMsgQueryTest.126
07:25:39,734 FIN | [DbSessionImpl] deleting process instance ErrorMsgQueryTest.127
07:25:39,739 FIN | [DbSessionImpl] deleting process instance ErrorMsgQueryTest.128
07:25:39,745 SEV | [Db] FIXME: JBPM-2004 3 records left in table JBPM4_HIST_PROCINST
07:25:39,750 FIN | [BaseJbpmTestCase] === ending testErrorMessages
=============================
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.596 sec
Running org.jbpm.test.history.EndProcessInstanceTest
07:25:39,761 FIN | [BaseJbpmTestCase] === starting testCancelProcessInstance
=============================
07:25:39,778 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:39,782 FIN | [DefaultIdGenerator] generated execution id ICL.129
07:25:39,783 FIN | [ExecuteActivity] executing activity(10388370)
07:25:39,785 FIN | [ExecuteActivity] executing activity(wait)
07:25:39,789 FIN | [Execution] process-instance ends with state cancel
07:25:39,793 FIN | [DbSessionImpl] deleting process instance ICL.129
07:25:39,818 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
07:25:39,819 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:39,824 FIN | [BaseJbpmTestCase] === ending testCancelProcessInstance
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.072 sec
Running org.jbpm.test.task.TaskParticipationsTest
07:25:39,835 FIN | [BaseJbpmTestCase] === starting testTaskParticipants
=============================
07:25:39,862 FIN | [BaseJbpmTestCase] === ending testTaskParticipants
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.036 sec
Running org.jbpm.test.activities.StateTest
07:25:39,885 FIN | [BaseJbpmTestCase] === starting testWaitStatesSequence
=============================
07:25:39,946 FIN | [ProcessDefinitionImpl] creating new execution for process
'ThreeStates'
07:25:39,948 FIN | [DefaultIdGenerator] generated execution id ThreeStates.130
07:25:39,950 FIN | [ExecuteActivity] executing activity(a)
07:25:39,951 FIN | [ExecuteActivity] executing activity(b)
07:25:39,955 FIN | [Signal] signalling activity(b), signalName=null
07:25:39,958 FIN | [ExecuteActivity] executing activity(c)
07:25:39,964 FIN | [Signal] signalling activity(c), signalName=null
07:25:39,969 FIN | [ExecuteActivity] executing activity(d)
07:25:39,971 FIN | [Execution] process-instance ends
07:25:39,976 FIN | [DbSessionImpl] deleting process instance ThreeStates.130
07:25:39,990 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
07:25:39,992 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:39,998 FIN | [BaseJbpmTestCase] === ending testWaitStatesSequence
=============================
07:25:40,001 FIN | [BaseJbpmTestCase] === starting testExternalDecision
=============================
07:25:40,044 FIN | [ProcessDefinitionImpl] creating new execution for process 'p'
07:25:40,047 FIN | [DefaultIdGenerator] generated execution id p.one
07:25:40,049 FIN | [ExecuteActivity] executing activity(10408558)
07:25:40,053 FIN | [ExecuteActivity] executing activity(ed)
07:25:40,056 FIN | [Signal] signalling activity(ed), signalName=left
07:25:40,060 FIN | [ExecuteActivity] executing activity(b)
07:25:40,063 FIN | [ProcessDefinitionImpl] creating new execution for process 'p'
07:25:40,065 FIN | [DefaultIdGenerator] generated execution id p.two
07:25:40,067 FIN | [ExecuteActivity] executing activity(10408558)
07:25:40,068 FIN | [ExecuteActivity] executing activity(ed)
07:25:40,075 FIN | [Signal] signalling activity(ed), signalName=middle
07:25:40,077 FIN | [ExecuteActivity] executing activity(c)
07:25:40,081 FIN | [ProcessDefinitionImpl] creating new execution for process 'p'
07:25:40,083 FIN | [DefaultIdGenerator] generated execution id p.three
07:25:40,085 FIN | [ExecuteActivity] executing activity(10408558)
07:25:40,087 FIN | [ExecuteActivity] executing activity(ed)
07:25:40,090 FIN | [Signal] signalling activity(ed), signalName=right
07:25:40,093 FIN | [ExecuteActivity] executing activity(d)
07:25:40,106 FIN | [DbSessionImpl] deleting process instance p.one
07:25:40,109 FIN | [DbSessionImpl] deleting process instance p.two
07:25:40,113 FIN | [DbSessionImpl] deleting process instance p.three
07:25:40,117 SEV | [Db] FIXME: JBPM-2004 6 records left in table JBPM4_HIST_ACTINST
07:25:40,120 SEV | [Db] FIXME: JBPM-2004 3 records left in table JBPM4_HIST_PROCINST
07:25:40,127 FIN | [BaseJbpmTestCase] === ending testExternalDecision
=============================
07:25:40,129 FIN | [BaseJbpmTestCase] === starting testDefaultSignalWithNamedTransitions
=============================
07:25:40,147 FIN | [ProcessDefinitionImpl] creating new execution for process 'p'
07:25:40,148 FIN | [DefaultIdGenerator] generated execution id p.one
07:25:40,152 FIN | [ExecuteActivity] executing activity(14056189)
07:25:40,153 FIN | [ExecuteActivity] executing activity(a)
07:25:40,157 FIN | [Signal] signalling activity(a), signalName=left
07:25:40,160 FIN | [ExecuteActivity] executing activity(b)
07:25:40,173 FIN | [DbSessionImpl] deleting process instance p.one
07:25:40,177 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
07:25:40,179 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,187 FIN | [BaseJbpmTestCase] === ending testDefaultSignalWithNamedTransitions
=============================
07:25:40,190 FIN | [BaseJbpmTestCase] === starting
testNamedSignalWithoutMatchingTransition =============================
07:25:40,216 FIN | [ProcessDefinitionImpl] creating new execution for process 'p'
07:25:40,218 FIN | [DefaultIdGenerator] generated execution id p.one
07:25:40,220 FIN | [ExecuteActivity] executing activity(33096927)
07:25:40,222 FIN | [ExecuteActivity] executing activity(a)
07:25:40,229 FIN | [Signal] signalling activity(a), signalName=up
07:25:40,243 FIN | [DbSessionImpl] deleting process instance p.one
07:25:40,247 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
07:25:40,250 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,258 FIN | [BaseJbpmTestCase] === ending testNamedSignalWithoutMatchingTransition
=============================
07:25:40,259 FIN | [BaseJbpmTestCase] === starting testDefaultSignalWithoutTransitions
=============================
07:25:40,277 FIN | [ProcessDefinitionImpl] creating new execution for process 'p'
07:25:40,279 FIN | [DefaultIdGenerator] generated execution id p.one
07:25:40,283 FIN | [ExecuteActivity] executing activity(31824916)
07:25:40,284 FIN | [ExecuteActivity] executing activity(a)
07:25:40,288 FIN | [Signal] signalling activity(a), signalName=null
07:25:40,302 FIN | [DbSessionImpl] deleting process instance p.one
07:25:40,306 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
07:25:40,308 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,314 FIN | [BaseJbpmTestCase] === ending testDefaultSignalWithoutTransitions
=============================
Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.439 sec
Running org.jbpm.test.eventlistener.EventListenerTest
07:25:40,326 FIN | [BaseJbpmTestCase] === starting testProcessStartListener
=============================
07:25:40,343 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:40,346 FIN | [DefaultIdGenerator] generated execution id ICL.137
07:25:40,348 FIN | [ExecuteActivity] executing activity(18529052)
07:25:40,349 FIN | [ExecuteActivity] executing activity(a)
07:25:40,369 FIN | [DbSessionImpl] deleting process instance ICL.137
07:25:40,373 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
07:25:40,375 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,381 FIN | [BaseJbpmTestCase] === ending testProcessStartListener
=============================
07:25:40,384 FIN | [BaseJbpmTestCase] === starting testProcessEndListener
=============================
07:25:40,404 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:40,412 FIN | [DefaultIdGenerator] generated execution id ICL.138
07:25:40,415 FIN | [ExecuteActivity] executing activity(16680080)
07:25:40,418 FIN | [ExecuteActivity] executing activity(end)
07:25:40,419 FIN | [Execution] process-instance ends
07:25:40,422 FIN | [DbSessionImpl] deleting process instance ICL.138
07:25:40,439 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,443 FIN | [BaseJbpmTestCase] === ending testProcessEndListener
=============================
07:25:40,446 FIN | [BaseJbpmTestCase] === starting testActivityStartListener
=============================
07:25:40,466 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:40,469 FIN | [DefaultIdGenerator] generated execution id ICL.139
07:25:40,470 FIN | [ExecuteActivity] executing activity(10876018)
07:25:40,473 FIN | [ExecuteActivity] executing activity(s)
07:25:40,488 FIN | [DbSessionImpl] deleting process instance ICL.139
07:25:40,492 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
07:25:40,494 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,502 FIN | [BaseJbpmTestCase] === ending testActivityStartListener
=============================
07:25:40,504 FIN | [BaseJbpmTestCase] === starting testActivityEndListener
=============================
07:25:40,521 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:40,524 FIN | [DefaultIdGenerator] generated execution id ICL.140
07:25:40,526 FIN | [ExecuteActivity] executing activity(20208369)
07:25:40,527 FIN | [ExecuteActivity] executing activity(s)
07:25:40,532 FIN | [Signal] signalling activity(s), signalName=null
07:25:40,535 FIN | [ExecuteActivity] executing activity(end)
07:25:40,537 FIN | [Execution] process-instance ends
07:25:40,540 FIN | [DbSessionImpl] deleting process instance ICL.140
07:25:40,567 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
07:25:40,573 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,586 FIN | [BaseJbpmTestCase] === ending testActivityEndListener
=============================
07:25:40,598 FIN | [BaseJbpmTestCase] === starting testTransitionListener
=============================
07:25:40,639 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:40,646 FIN | [DefaultIdGenerator] generated execution id ICL.141
07:25:40,652 FIN | [ExecuteActivity] executing activity(3073770)
07:25:40,655 FIN | [ExecuteActivity] executing activity(end)
07:25:40,657 FIN | [Execution] process-instance ends
07:25:40,660 FIN | [DbSessionImpl] deleting process instance ICL.141
07:25:40,676 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,682 FIN | [BaseJbpmTestCase] === ending testTransitionListener
=============================
07:25:40,685 FIN | [BaseJbpmTestCase] === starting testEventOrdering
=============================
07:25:40,706 FIN | [ProcessDefinitionImpl] creating new execution for process
'Insurance claim'
07:25:40,708 FIN | [DefaultIdGenerator] generated execution id ICL.142
07:25:40,710 FIN | [ExecuteActivity] executing activity(29777818)
07:25:40,712 FIN | [ExecuteActivity] executing activity(end)
07:25:40,714 FIN | [Execution] process-instance ends
07:25:40,719 FIN | [DbSessionImpl] deleting process instance ICL.142
07:25:40,757 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,785 FIN | [BaseJbpmTestCase] === ending testEventOrdering
=============================
Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.476 sec
Running org.jbpm.test.activities.ForkToTaskTest
07:25:40,820 FIN | [BaseJbpmTestCase] === starting testForkToTasks
=============================
07:25:40,892 FIN | [ProcessDefinitionImpl] creating new execution for process
'ForkToTasks'
07:25:40,895 FIN | [DefaultIdGenerator] generated execution id ForkToTasks.143
07:25:40,897 FIN | [ExecuteActivity] executing activity(30526889)
07:25:40,898 FIN | [ExecuteActivity] executing activity(f)
07:25:40,900 FIN | [Execution] creating execution
07:25:40,903 FIN | [DefaultIdGenerator] generated execution id ForkToTasks.143.144[144]
07:25:40,905 FIN | [ExecuteActivity] executing activity(assemble product)
07:25:40,908 FIN | [Execution] creating execution
07:25:40,910 FIN | [DefaultIdGenerator] generated execution id ForkToTasks.143.145[145]
07:25:40,912 FIN | [ExecuteActivity] executing activity(print documents)
07:25:40,931 FIN | [DbSessionImpl] deleting process instance ForkToTasks.143
07:25:40,937 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
07:25:40,939 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
07:25:40,945 FIN | [BaseJbpmTestCase] === ending testForkToTasks
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.136 sec
Results :
Tests in error:
testTaskHandlerBasicScenario(org.jbpm.test.task.TaskHandlerTest)
testTaskHandlerCancelScenario(org.jbpm.test.task.TaskHandlerTest)
testSimplestTaskQuery(org.jbpm.test.task.TaskQueryTest)
testSimplestTaskQuerySortBy(org.jbpm.test.task.TaskQueryTest)
Tests run: 126, Failures: 0, Errors: 4, Skipped: 0
[INFO] ------------------------------------------------------------------------
[ERROR] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] There are test failures.
Please refer to
/qa/services/hudson/hudson_workspace/workspace/jbpm4-base/jdk/java15_default/label/linux/jbpm4/modules/test-db/target/surefire-reports
for the individual test results.
[INFO] ------------------------------------------------------------------------
[INFO] For more information, run Maven with the -e switch
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 2 minutes 14 seconds
[INFO] Finished at: Thu Jun 04 07:25:41 EDT 2009
[INFO] Final Memory: 27M/63M
[INFO] ------------------------------------------------------------------------
Recording test results