[jbpm-dev] Build failed in Hudson: jbpm4-base » java15_default,linux #259
jboss-qa-internal at redhat.com
jboss-qa-internal at redhat.com
Tue May 26 09:38:05 EDT 2009
See http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=java15_default,label=linux/259/changes
Changes:
[tom.baeyens at jboss.com] fixed integration test suite: removed use of synchronization for process instance end deletion and refactored example email templates
------------------------------------------
[...truncated 2796 lines...]
09:37:57,183 FIN | [ExecuteJobCmd] executing job timer[8|2009-05-26 09:47:57,172|timeout]...
09:37:57,185 FIN | [TimerImpl] executing timer[8|2009-05-26 09:47:57,172|timeout]
09:37:57,188 FIN | [TimerImpl] firing event null into activity(guardedWait)
09:37:57,191 FIN | [ScopeInstanceImpl] create variable 'escalation' in 'process-instance' with value 'true'
09:37:57,198 FIN | [TimerImpl] deleting timer[8|2009-05-26 09:47:57,172|timeout]
09:37:57,200 FIN | [ExecuteJobCmd] executed job timer[8|2009-05-26 09:47:57,172|timeout]
09:37:57,220 FIN | [HibernatePvmDbSession] deleting process instance TimerEvent.56
09:37:57,227 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
09:37:57,229 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:57,235 FIN | [BaseJbpmTestCase] === ending testTimerEventTimerFires =============================
09:37:57,238 FIN | [BaseJbpmTestCase] === starting testTimerEventContinueBeforeTimerFires =============================
09:37:57,256 FIN | [ProcessDefinitionImpl] creating new execution for process 'TimerEvent'
09:37:57,258 FIN | [DefaultIdGenerator] generated execution id TimerEvent.58
09:37:57,260 FIN | [ExecuteActivity] executing activity(5390241)
09:37:57,262 FIN | [TransitionEndActivity] taking -->(guardedWait)
09:37:57,264 FIN | [Execution] creating execution[guardedWait]
09:37:57,266 FIN | [DefaultIdGenerator] generated execution id TimerEvent.58.guardedWait[0]
09:37:57,268 FIN | [ScopeInstanceImpl] creating timer on execution[guardedWait]
09:37:57,270 FIN | [TimerSession] scheduling timer[0|2009-05-26 09:47:57,270|timeout]
09:37:57,273 FIN | [ExecuteActivity] execution[guardedWait] executes activity(guardedWait)
09:37:57,281 FIN | [Signal] execution[guardedWait] signals activity(guardedWait)
09:37:57,283 FIN | [TransitionEndActivity] execution[guardedWait] takes (guardedWait)--go on-->(next step)
09:37:57,286 FIN | [Execution] destroying timers of execution[guardedWait]
09:37:57,288 FIN | [TimerSession] canceling timer[9|2009-05-26 09:47:57,270|timeout]
09:37:57,291 FIN | [Execution] execution[guardedWait] ends
09:37:57,293 FIN | [ExecuteActivity] executing activity(next step)
09:37:57,311 FIN | [HibernatePvmDbSession] deleting process instance TimerEvent.58
09:37:57,318 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
09:37:57,320 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:57,326 FIN | [BaseJbpmTestCase] === ending testTimerEventContinueBeforeTimerFires =============================
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.23 sec
Running org.jbpm.examples.async.activity.AsyncActivityTest
09:37:57,347 FIN | [BaseJbpmTestCase] === starting testAsyncActivity =============================
09:37:57,420 FIN | [ProcessDefinitionImpl] creating new execution for process 'AsyncActivity'
09:37:57,424 FIN | [DefaultIdGenerator] generated execution id AsyncActivity.60
09:37:57,427 FIN | [ExecuteActivity] executing activity(18109858)
09:37:57,430 FIN | [TransitionEndActivity] taking -->(generate pdf)
09:37:57,432 FIN | [JobExecutorMessageSession] sending message ExecuteActivityMessage
09:37:57,438 FIN | [ExecuteJobCmd] executing job ExecuteActivityMessage[10]...
09:37:57,442 FIN | [ExecuteActivity] executing activity(generate pdf)
09:37:57,447 FIN | [TransitionEndActivity] taking (generate pdf)-->(calculate primes)
09:37:57,449 FIN | [JobExecutorMessageSession] sending message ExecuteActivityMessage
09:37:57,451 FIN | [ExecuteJobCmd] executed job ExecuteActivityMessage[10]
09:37:57,457 FIN | [ExecuteJobCmd] executing job ExecuteActivityMessage[11]...
09:37:57,461 FIN | [ExecuteActivity] executing activity(calculate primes)
09:37:57,463 FIN | [TransitionEndActivity] taking (calculate primes)-->(end)
09:37:57,465 FIN | [ExecuteActivity] executing activity(end)
09:37:57,467 FIN | [Execution] process-instance ends with state ended
09:37:57,470 FIN | [HibernatePvmDbSession] deleting process instance AsyncActivity.60
09:37:57,473 FIN | [ExecuteJobCmd] executed job ExecuteActivityMessage[11]
09:37:57,489 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
09:37:57,491 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:57,497 FIN | [BaseJbpmTestCase] === ending testAsyncActivity =============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.16 sec
Running org.jbpm.examples.end.multiple.EndMultipleTest
09:37:57,523 FIN | [BaseJbpmTestCase] === starting testEndMultipleOk =============================
09:37:57,571 FIN | [ProcessDefinitionImpl] creating new execution for process 'EndMultiple'
09:37:57,574 FIN | [DefaultIdGenerator] generated execution id EndMultiple.61
09:37:57,576 FIN | [ExecuteActivity] executing activity(21595430)
09:37:57,580 FIN | [TransitionEndActivity] taking ---->(get return code)
09:37:57,582 FIN | [ExecuteActivity] executing activity(get return code)
09:37:57,587 FIN | [Signal] signalling activity(get return code), signalName=200
09:37:57,589 FIN | [TransitionEndActivity] taking (get return code)--200-->(ok)
09:37:57,593 FIN | [ExecuteActivity] executing activity(ok)
09:37:57,595 FIN | [Execution] process-instance ends with state ended
09:37:57,599 FIN | [HibernatePvmDbSession] deleting process instance EndMultiple.61
09:37:57,615 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
09:37:57,618 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:57,625 FIN | [BaseJbpmTestCase] === ending testEndMultipleOk =============================
09:37:57,628 FIN | [BaseJbpmTestCase] === starting testEndMultipleBadRequest =============================
09:37:57,651 FIN | [ProcessDefinitionImpl] creating new execution for process 'EndMultiple'
09:37:57,653 FIN | [DefaultIdGenerator] generated execution id EndMultiple.62
09:37:57,656 FIN | [ExecuteActivity] executing activity(13772551)
09:37:57,656 FIN | [TransitionEndActivity] taking ---->(get return code)
09:37:57,659 FIN | [ExecuteActivity] executing activity(get return code)
09:37:57,665 FIN | [Signal] signalling activity(get return code), signalName=400
09:37:57,668 FIN | [TransitionEndActivity] taking (get return code)--400-->(bad request)
09:37:57,669 FIN | [ExecuteActivity] executing activity(bad request)
09:37:57,672 FIN | [Execution] process-instance ends with state ended
09:37:57,674 FIN | [HibernatePvmDbSession] deleting process instance EndMultiple.62
09:37:57,692 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
09:37:57,693 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:57,704 FIN | [BaseJbpmTestCase] === ending testEndMultipleBadRequest =============================
09:37:57,707 FIN | [BaseJbpmTestCase] === starting testEndMultipleInternalServerError =============================
09:37:57,727 FIN | [ProcessDefinitionImpl] creating new execution for process 'EndMultiple'
09:37:57,730 FIN | [DefaultIdGenerator] generated execution id EndMultiple.63
09:37:57,732 FIN | [ExecuteActivity] executing activity(3277398)
09:37:57,734 FIN | [TransitionEndActivity] taking ---->(get return code)
09:37:57,736 FIN | [ExecuteActivity] executing activity(get return code)
09:37:57,740 FIN | [Signal] signalling activity(get return code), signalName=500
09:37:57,743 FIN | [TransitionEndActivity] taking (get return code)--500-->(internal server error)
09:37:57,745 FIN | [ExecuteActivity] executing activity(internal server error)
09:37:57,745 FIN | [Execution] process-instance ends with state ended
09:37:57,750 FIN | [HibernatePvmDbSession] deleting process instance EndMultiple.63
09:37:57,767 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
09:37:57,770 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:57,776 FIN | [BaseJbpmTestCase] === ending testEndMultipleInternalServerError =============================
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.271 sec
Running org.jbpm.examples.subprocess.outcomeactivity.SubProcessOutcomeActivityTest
09:37:57,810 FIN | [BaseJbpmTestCase] === starting testSubProcessResultOk =============================
09:37:58,193 FIN | [ProcessDefinitionImpl] creating new execution for process 'SubProcessDocument'
09:37:58,197 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.64
09:37:58,200 FIN | [ExecuteActivity] executing activity(10062857)
09:37:58,202 FIN | [TransitionEndActivity] taking -->(review)
09:37:58,202 FIN | [ExecuteActivity] executing activity(review)
09:37:58,205 FIN | [ProcessDefinitionImpl] creating new execution for process 'SubProcessReview'
09:37:58,209 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.65
09:37:58,211 FIN | [ExecuteActivity] executing activity(10483841)
09:37:58,213 FIN | [TransitionEndActivity] taking -->(get approval)
09:37:58,213 FIN | [ExecuteActivity] executing activity(get approval)
09:37:58,218 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.state != 'suspended' and task.assignee = :assignee order by task.priority desc
09:37:58,221 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
09:37:58,227 FIN | [Signal] signalling activity(get approval), signalName=ok
09:37:58,231 FIN | [TransitionEndActivity] taking (get approval)--ok-->(ok)
09:37:58,232 FIN | [ExecuteActivity] executing activity(ok)
09:37:58,235 FIN | [Execution] process-instance ends with state ended
09:37:58,242 FIN | [Signal] signalling activity(review), signalName=null
09:37:58,245 WRN | [ProxyWarnLog] Narrowing proxy to class org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
09:37:58,247 FIN | [TransitionEndActivity] taking (review)--ok-->(next step)
09:37:58,249 FIN | [ExecuteActivity] executing activity(next step)
09:37:58,254 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.65
09:37:58,286 FIN | [HibernatePvmDbSession] deleting process instance SubProcessDocument.64
09:37:58,293 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
09:37:58,295 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_PROCINST
09:37:58,304 FIN | [BaseJbpmTestCase] === ending testSubProcessResultOk =============================
09:37:58,306 FIN | [BaseJbpmTestCase] === starting testSubProcessResultNok =============================
09:37:58,346 FIN | [ProcessDefinitionImpl] creating new execution for process 'SubProcessDocument'
09:37:58,348 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.66
09:37:58,351 FIN | [ExecuteActivity] executing activity(4746525)
09:37:58,354 FIN | [TransitionEndActivity] taking -->(review)
09:37:58,354 FIN | [ExecuteActivity] executing activity(review)
09:37:58,360 FIN | [ProcessDefinitionImpl] creating new execution for process 'SubProcessReview'
09:37:58,364 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.67
09:37:58,365 FIN | [ExecuteActivity] executing activity(10883955)
09:37:58,366 FIN | [TransitionEndActivity] taking -->(get approval)
09:37:58,369 FIN | [ExecuteActivity] executing activity(get approval)
09:37:58,372 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.state != 'suspended' and task.assignee = :assignee order by task.priority desc
09:37:58,374 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
09:37:58,380 FIN | [Signal] signalling activity(get approval), signalName=nok
09:37:58,384 FIN | [TransitionEndActivity] taking (get approval)--nok-->(nok)
09:37:58,386 FIN | [ExecuteActivity] executing activity(nok)
09:37:58,387 FIN | [Execution] process-instance ends with state ended
09:37:58,391 FIN | [Signal] signalling activity(review), signalName=null
09:37:58,395 WRN | [ProxyWarnLog] Narrowing proxy to class org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
09:37:58,397 FIN | [TransitionEndActivity] taking (review)--nok-->(update)
09:37:58,398 FIN | [ExecuteActivity] executing activity(update)
09:37:58,402 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.67
09:37:58,432 FIN | [HibernatePvmDbSession] deleting process instance SubProcessDocument.66
09:37:58,437 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
09:37:58,438 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_PROCINST
09:37:58,447 FIN | [BaseJbpmTestCase] === ending testSubProcessResultNok =============================
09:37:58,450 FIN | [BaseJbpmTestCase] === starting testSubProcessResultReject =============================
09:37:58,479 FIN | [ProcessDefinitionImpl] creating new execution for process 'SubProcessDocument'
09:37:58,482 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.68
09:37:58,484 FIN | [ExecuteActivity] executing activity(29067817)
09:37:58,484 FIN | [TransitionEndActivity] taking -->(review)
09:37:58,487 FIN | [ExecuteActivity] executing activity(review)
09:37:58,490 FIN | [ProcessDefinitionImpl] creating new execution for process 'SubProcessReview'
09:37:58,492 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.69
09:37:58,495 FIN | [ExecuteActivity] executing activity(6499959)
09:37:58,497 FIN | [TransitionEndActivity] taking -->(get approval)
09:37:58,497 FIN | [ExecuteActivity] executing activity(get approval)
09:37:58,502 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.state != 'suspended' and task.assignee = :assignee order by task.priority desc
09:37:58,505 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
09:37:58,510 FIN | [Signal] signalling activity(get approval), signalName=reject
09:37:58,513 FIN | [TransitionEndActivity] taking (get approval)--reject-->(reject)
09:37:58,515 FIN | [ExecuteActivity] executing activity(reject)
09:37:58,515 FIN | [Execution] process-instance ends with state ended
09:37:58,523 FIN | [Signal] signalling activity(review), signalName=null
09:37:58,526 WRN | [ProxyWarnLog] Narrowing proxy to class org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
09:37:58,527 FIN | [TransitionEndActivity] taking (review)--reject-->(close)
09:37:58,528 FIN | [ExecuteActivity] executing activity(close)
09:37:58,534 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.69
09:37:58,560 FIN | [HibernatePvmDbSession] deleting process instance SubProcessDocument.68
09:37:58,572 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
09:37:58,573 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_PROCINST
09:37:58,580 FIN | [BaseJbpmTestCase] === ending testSubProcessResultReject =============================
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.778 sec
Running org.jbpm.examples.services.ServicesTest
09:37:58,619 INF | [Configuration] configuring from resource: jbpm.hibernate.cfg.xml
09:37:58,621 INF | [Configuration] Configuration resource: jbpm.hibernate.cfg.xml
09:37:58,628 INF | [Configuration] Reading mappings from resource : jbpm.repository.hbm.xml
09:37:58,643 INF | [Configuration] Reading mappings from resource : jbpm.execution.hbm.xml
09:37:58,679 INF | [Configuration] Reading mappings from resource : jbpm.history.hbm.xml
09:37:58,691 INF | [Configuration] Reading mappings from resource : jbpm.task.hbm.xml
09:37:58,711 INF | [Configuration] Reading mappings from resource : jbpm.jpdl.hbm.xml
09:37:58,722 INF | [Configuration] Reading mappings from resource : jbpm.identity.hbm.xml
09:37:58,735 INF | [Configuration] Configured SessionFactory: null
09:37:58,743 INF | [DriverManagerConnectionProvider] Using Hibernate built-in connection pool (not for production use!)
09:37:58,745 INF | [DriverManagerConnectionProvider] Hibernate connection pool size: 20
09:37:58,747 INF | [DriverManagerConnectionProvider] autocommit mode: false
09:37:58,749 INF | [DriverManagerConnectionProvider] using driver: org.hsqldb.jdbcDriver at URL: jdbc:hsqldb:mem:.
09:37:58,750 INF | [DriverManagerConnectionProvider] connection properties: {user=sa, password=****}
09:37:58,755 INF | [Dialect] Using dialect: org.hibernate.dialect.HSQLDialect
09:37:58,757 INF | [TransactionFactoryFactory] Using default transaction strategy (direct JDBC transactions)
09:37:58,759 INF | [TransactionManagerLookupFactory] No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
09:37:58,761 INF | [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
09:37:58,770 INF | [SessionFactoryImpl] building session factory
09:37:59,038 INF | [SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name configured
09:37:59,044 INF | [SchemaExport] Running hbm2ddl schema export
09:37:59,048 INF | [SchemaExport] exporting generated schema to database
09:37:59,060 INF | [SchemaExport] schema export complete
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.582 sec
Running org.jbpm.examples.task.assignmenthandler.TaskAssignmentHandlerTest
09:37:59,175 FIN | [BaseJbpmTestCase] === starting testTaskAssignmentHandler =============================
09:37:59,226 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskAssignmentHandler'
09:37:59,230 FIN | [DefaultIdGenerator] generated execution id TaskAssignmentHandler.1
09:37:59,233 FIN | [ExecuteActivity] executing activity(26650284)
09:37:59,234 FIN | [TransitionEndActivity] taking -->(review)
09:37:59,237 FIN | [ExecuteActivity] executing activity(review)
09:37:59,242 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.state != 'suspended' and task.assignee = :assignee order by task.priority desc
09:37:59,243 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
09:37:59,260 FIN | [HibernatePvmDbSession] deleting process instance TaskAssignmentHandler.1
09:37:59,267 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
09:37:59,270 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
09:37:59,287 FIN | [BaseJbpmTestCase] === ending testTaskAssignmentHandler =============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.122 sec
Results :
Tests in error:
testTemplateMail(org.jbpm.examples.mail.template.TemplateMailTest)
Tests run: 52, Failures: 0, Errors: 1, 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/examples/target/surefire-reports for the individual test results.
[INFO] ------------------------------------------------------------------------
[INFO] For more information, run Maven with the -e switch
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 1 minute 13 seconds
[INFO] Finished at: Tue May 26 09:37:59 EDT 2009
[INFO] Final Memory: 31M/64M
[INFO] ------------------------------------------------------------------------
Recording test results
More information about the jbpm-dev
mailing list