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