10:55 a.m.
See
http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=java15_default,lab...
Changes:
[heiko.braun(a)jboss.com] More work on reports templates
[heiko.braun(a)jboss.com] Prevent NPE in ModelAdapter
------------------------------------------
[...truncated 2795 lines...]
11:55:03,034 FIN | [ExecuteJobCmd] executing job timer[8|2009-05-26
12:05:03,011|timeout]...
11:55:03,034 FIN | [TimerImpl] executing timer[8|2009-05-26 12:05:03,011|timeout]
11:55:03,040 FIN | [TimerImpl] firing event null into activity(guardedWait)
11:55:03,043 FIN | [ScopeInstanceImpl] create variable 'escalation' in
'process-instance' with value 'true'
11:55:03,044 FIN | [TimerImpl] deleting timer[8|2009-05-26 12:05:03,011|timeout]
11:55:03,045 FIN | [ExecuteJobCmd] executed job timer[8|2009-05-26 12:05:03,011|timeout]
11:55:03,096 FIN | [HibernatePvmDbSession] deleting process instance TimerEvent.56
11:55:03,111 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
11:55:03,112 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:03,134 FIN | [BaseJbpmTestCase] === ending testTimerEventTimerFires
=============================
11:55:03,136 FIN | [BaseJbpmTestCase] === starting testTimerEventContinueBeforeTimerFires
=============================
11:55:03,181 FIN | [ProcessDefinitionImpl] creating new execution for process
'TimerEvent'
11:55:03,183 FIN | [DefaultIdGenerator] generated execution id TimerEvent.58
11:55:03,184 FIN | [ExecuteActivity] executing activity(44475574)
11:55:03,184 FIN | [TransitionEndActivity] taking -->(guardedWait)
11:55:03,185 FIN | [Execution] creating execution[guardedWait]
11:55:03,185 FIN | [DefaultIdGenerator] generated execution id
TimerEvent.58.guardedWait[0]
11:55:03,185 FIN | [ScopeInstanceImpl] creating timer on execution[guardedWait]
11:55:03,187 FIN | [TimerSession] scheduling timer[0|2009-05-26 12:05:03,186|timeout]
11:55:03,189 FIN | [ExecuteActivity] execution[guardedWait] executes
activity(guardedWait)
11:55:03,202 FIN | [Signal] execution[guardedWait] signals activity(guardedWait)
11:55:03,204 FIN | [TransitionEndActivity] execution[guardedWait] takes (guardedWait)--go
on-->(next step)
11:55:03,206 FIN | [Execution] destroying timers of execution[guardedWait]
11:55:03,209 FIN | [TimerSession] canceling timer[9|2009-05-26 12:05:03,186|timeout]
11:55:03,212 FIN | [Execution] execution[guardedWait] ends
11:55:03,213 FIN | [ExecuteActivity] executing activity(next step)
11:55:03,257 FIN | [HibernatePvmDbSession] deleting process instance TimerEvent.58
11:55:03,268 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
11:55:03,269 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:03,291 FIN | [BaseJbpmTestCase] === ending testTimerEventContinueBeforeTimerFires
=============================
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.419 sec
Running org.jbpm.examples.async.activity.AsyncActivityTest
11:55:03,315 FIN | [BaseJbpmTestCase] === starting testAsyncActivity
=============================
11:55:03,436 FIN | [ProcessDefinitionImpl] creating new execution for process
'AsyncActivity'
11:55:03,440 FIN | [DefaultIdGenerator] generated execution id AsyncActivity.60
11:55:03,443 FIN | [ExecuteActivity] executing activity(1501500357)
11:55:03,443 FIN | [TransitionEndActivity] taking -->(generate pdf)
11:55:03,445 FIN | [JobExecutorMessageSession] sending message ExecuteActivityMessage
11:55:03,459 FIN | [ExecuteJobCmd] executing job ExecuteActivityMessage[10]...
11:55:03,465 FIN | [ExecuteActivity] executing activity(generate pdf)
11:55:03,471 FIN | [TransitionEndActivity] taking (generate pdf)-->(calculate primes)
11:55:03,473 FIN | [JobExecutorMessageSession] sending message ExecuteActivityMessage
11:55:03,476 FIN | [ExecuteJobCmd] executed job ExecuteActivityMessage[10]
11:55:03,487 FIN | [ExecuteJobCmd] executing job ExecuteActivityMessage[11]...
11:55:03,493 FIN | [ExecuteActivity] executing activity(calculate primes)
11:55:03,495 FIN | [TransitionEndActivity] taking (calculate primes)-->(end)
11:55:03,496 FIN | [ExecuteActivity] executing activity(end)
11:55:03,497 FIN | [Execution] process-instance ends with state ended
11:55:03,504 FIN | [HibernatePvmDbSession] deleting process instance AsyncActivity.60
11:55:03,507 FIN | [ExecuteJobCmd] executed job ExecuteActivityMessage[11]
11:55:03,548 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
11:55:03,550 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:03,572 FIN | [BaseJbpmTestCase] === ending testAsyncActivity
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.272 sec
Running org.jbpm.examples.end.multiple.EndMultipleTest
11:55:03,587 FIN | [BaseJbpmTestCase] === starting testEndMultipleOk
=============================
11:55:03,705 FIN | [ProcessDefinitionImpl] creating new execution for process
'EndMultiple'
11:55:03,706 FIN | [DefaultIdGenerator] generated execution id EndMultiple.61
11:55:03,707 FIN | [ExecuteActivity] executing activity(842340458)
11:55:03,707 FIN | [TransitionEndActivity] taking ---->(get return code)
11:55:03,708 FIN | [ExecuteActivity] executing activity(get return code)
11:55:03,717 FIN | [Signal] signalling activity(get return code), signalName=200
11:55:03,719 FIN | [TransitionEndActivity] taking (get return code)--200-->(ok)
11:55:03,720 FIN | [ExecuteActivity] executing activity(ok)
11:55:03,720 FIN | [Execution] process-instance ends with state ended
11:55:03,729 FIN | [HibernatePvmDbSession] deleting process instance EndMultiple.61
11:55:03,767 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
11:55:03,768 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:03,790 FIN | [BaseJbpmTestCase] === ending testEndMultipleOk
=============================
11:55:03,792 FIN | [BaseJbpmTestCase] === starting testEndMultipleBadRequest
=============================
11:55:03,836 FIN | [ProcessDefinitionImpl] creating new execution for process
'EndMultiple'
11:55:03,838 FIN | [DefaultIdGenerator] generated execution id EndMultiple.62
11:55:03,839 FIN | [ExecuteActivity] executing activity(995821364)
11:55:03,840 FIN | [TransitionEndActivity] taking ---->(get return code)
11:55:03,840 FIN | [ExecuteActivity] executing activity(get return code)
11:55:03,850 FIN | [Signal] signalling activity(get return code), signalName=400
11:55:03,852 FIN | [TransitionEndActivity] taking (get return code)--400-->(bad
request)
11:55:03,853 FIN | [ExecuteActivity] executing activity(bad request)
11:55:03,853 FIN | [Execution] process-instance ends with state ended
11:55:03,861 FIN | [HibernatePvmDbSession] deleting process instance EndMultiple.62
11:55:03,898 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
11:55:03,900 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:03,920 FIN | [BaseJbpmTestCase] === ending testEndMultipleBadRequest
=============================
11:55:03,922 FIN | [BaseJbpmTestCase] === starting testEndMultipleInternalServerError
=============================
11:55:03,967 FIN | [ProcessDefinitionImpl] creating new execution for process
'EndMultiple'
11:55:03,969 FIN | [DefaultIdGenerator] generated execution id EndMultiple.63
11:55:03,970 FIN | [ExecuteActivity] executing activity(1075012607)
11:55:03,971 FIN | [TransitionEndActivity] taking ---->(get return code)
11:55:03,972 FIN | [ExecuteActivity] executing activity(get return code)
11:55:03,981 FIN | [Signal] signalling activity(get return code), signalName=500
11:55:03,983 FIN | [TransitionEndActivity] taking (get return code)--500-->(internal
server error)
11:55:03,984 FIN | [ExecuteActivity] executing activity(internal server error)
11:55:03,985 FIN | [Execution] process-instance ends with state ended
11:55:03,992 FIN | [HibernatePvmDbSession] deleting process instance EndMultiple.63
11:55:04,028 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
11:55:04,029 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:04,048 FIN | [BaseJbpmTestCase] === ending testEndMultipleInternalServerError
=============================
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.472 sec
Running org.jbpm.examples.subprocess.outcomeactivity.SubProcessOutcomeActivityTest
11:55:04,062 FIN | [BaseJbpmTestCase] === starting testSubProcessResultOk
=============================
11:55:04,215 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:04,217 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.64
11:55:04,218 FIN | [ExecuteActivity] executing activity(639991987)
11:55:04,218 FIN | [TransitionEndActivity] taking -->(review)
11:55:04,220 FIN | [ExecuteActivity] executing activity(review)
11:55:04,225 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:04,227 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.65
11:55:04,228 FIN | [ExecuteActivity] executing activity(1527405623)
11:55:04,229 FIN | [TransitionEndActivity] taking -->(get approval)
11:55:04,230 FIN | [ExecuteActivity] executing activity(get approval)
11:55:04,239 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
11:55:04,239 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:04,251 FIN | [Signal] signalling activity(get approval), signalName=ok
11:55:04,253 FIN | [TransitionEndActivity] taking (get approval)--ok-->(ok)
11:55:04,254 FIN | [ExecuteActivity] executing activity(ok)
11:55:04,255 FIN | [Execution] process-instance ends with state ended
11:55:04,262 FIN | [Signal] signalling activity(review), signalName=null
11:55:04,264 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:04,265 FIN | [TransitionEndActivity] taking (review)--ok-->(next step)
11:55:04,266 FIN | [ExecuteActivity] executing activity(next step)
11:55:04,278 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.65
11:55:04,345 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.64
11:55:04,356 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
11:55:04,357 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_PROCINST
11:55:04,377 FIN | [BaseJbpmTestCase] === ending testSubProcessResultOk
=============================
11:55:04,379 FIN | [BaseJbpmTestCase] === starting testSubProcessResultNok
=============================
11:55:04,462 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:04,464 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.66
11:55:04,465 FIN | [ExecuteActivity] executing activity(1954650034)
11:55:04,465 FIN | [TransitionEndActivity] taking -->(review)
11:55:04,466 FIN | [ExecuteActivity] executing activity(review)
11:55:04,470 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:04,473 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.67
11:55:04,474 FIN | [ExecuteActivity] executing activity(67338754)
11:55:04,474 FIN | [TransitionEndActivity] taking -->(get approval)
11:55:04,475 FIN | [ExecuteActivity] executing activity(get approval)
11:55:04,484 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
11:55:04,484 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:04,495 FIN | [Signal] signalling activity(get approval), signalName=nok
11:55:04,498 FIN | [TransitionEndActivity] taking (get approval)--nok-->(nok)
11:55:04,499 FIN | [ExecuteActivity] executing activity(nok)
11:55:04,499 FIN | [Execution] process-instance ends with state ended
11:55:04,508 FIN | [Signal] signalling activity(review), signalName=null
11:55:04,510 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:04,511 FIN | [TransitionEndActivity] taking (review)--nok-->(update)
11:55:04,512 FIN | [ExecuteActivity] executing activity(update)
11:55:04,525 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.67
11:55:04,589 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.66
11:55:04,599 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
11:55:04,600 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_PROCINST
11:55:04,619 FIN | [BaseJbpmTestCase] === ending testSubProcessResultNok
=============================
11:55:04,621 FIN | [BaseJbpmTestCase] === starting testSubProcessResultReject
=============================
11:55:04,704 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:04,706 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.68
11:55:04,707 FIN | [ExecuteActivity] executing activity(1447892364)
11:55:04,708 FIN | [TransitionEndActivity] taking -->(review)
11:55:04,709 FIN | [ExecuteActivity] executing activity(review)
11:55:04,714 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:04,716 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.69
11:55:04,717 FIN | [ExecuteActivity] executing activity(826160875)
11:55:04,717 FIN | [TransitionEndActivity] taking -->(get approval)
11:55:04,718 FIN | [ExecuteActivity] executing activity(get approval)
11:55:04,727 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
11:55:04,727 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:04,738 FIN | [Signal] signalling activity(get approval), signalName=reject
11:55:04,741 FIN | [TransitionEndActivity] taking (get approval)--reject-->(reject)
11:55:04,742 FIN | [ExecuteActivity] executing activity(reject)
11:55:04,743 FIN | [Execution] process-instance ends with state ended
11:55:04,749 FIN | [Signal] signalling activity(review), signalName=null
11:55:04,751 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:04,752 FIN | [TransitionEndActivity] taking (review)--reject-->(close)
11:55:04,753 FIN | [ExecuteActivity] executing activity(close)
11:55:04,765 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.69
11:55:04,829 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.68
11:55:04,839 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_ACTINST
11:55:04,841 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM4_HIST_PROCINST
11:55:04,859 FIN | [BaseJbpmTestCase] === ending testSubProcessResultReject
=============================
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.807 sec
Running org.jbpm.examples.services.ServicesTest
11:55:04,934 INF | [Configuration] configuring from resource: jbpm.hibernate.cfg.xml
11:55:04,935 INF | [Configuration] Configuration resource: jbpm.hibernate.cfg.xml
11:55:04,953 INF | [Configuration] Reading mappings from resource :
jbpm.repository.hbm.xml
11:55:05,001 INF | [Configuration] Reading mappings from resource :
jbpm.execution.hbm.xml
11:55:05,102 INF | [Configuration] Reading mappings from resource : jbpm.history.hbm.xml
11:55:05,153 INF | [Configuration] Reading mappings from resource : jbpm.task.hbm.xml
11:55:05,212 INF | [Configuration] Reading mappings from resource : jbpm.jpdl.hbm.xml
11:55:05,252 INF | [Configuration] Reading mappings from resource : jbpm.identity.hbm.xml
11:55:05,298 INF | [Configuration] Configured SessionFactory: null
11:55:05,311 INF | [DriverManagerConnectionProvider] Using Hibernate built-in connection
pool (not for production use!)
11:55:05,312 INF | [DriverManagerConnectionProvider] Hibernate connection pool size: 20
11:55:05,314 INF | [DriverManagerConnectionProvider] autocommit mode: false
11:55:05,315 INF | [DriverManagerConnectionProvider] using driver: org.hsqldb.jdbcDriver
at URL: jdbc:hsqldb:mem:.
11:55:05,316 INF | [DriverManagerConnectionProvider] connection properties: {user=sa,
password=****}
11:55:05,319 INF | [Dialect] Using dialect: org.hibernate.dialect.HSQLDialect
11:55:05,321 INF | [TransactionFactoryFactory] Using default transaction strategy (direct
JDBC transactions)
11:55:05,322 INF | [TransactionManagerLookupFactory] No TransactionManagerLookup
configured (in JTA environment, use of read-write or transactional second-level cache is
not recommended)
11:55:05,324 INF | [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
11:55:05,335 INF | [SessionFactoryImpl] building session factory
11:55:05,730 INF | [SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name
configured
11:55:05,743 INF | [SchemaExport] Running hbm2ddl schema export
11:55:05,746 INF | [SchemaExport] exporting generated schema to database
11:55:05,776 INF | [SchemaExport] schema export complete
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.158 sec
Running org.jbpm.examples.task.assignmenthandler.TaskAssignmentHandlerTest
11:55:06,030 FIN | [BaseJbpmTestCase] === starting testTaskAssignmentHandler
=============================
11:55:06,138 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskAssignmentHandler'
11:55:06,140 FIN | [DefaultIdGenerator] generated execution id TaskAssignmentHandler.1
11:55:06,141 FIN | [ExecuteActivity] executing activity(20219443)
11:55:06,142 FIN | [TransitionEndActivity] taking -->(review)
11:55:06,143 FIN | [ExecuteActivity] executing activity(review)
11:55:06,152 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
11:55:06,152 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:06,163 FIN | [HibernatePvmDbSession] deleting process instance
TaskAssignmentHandler.1
11:55:06,173 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_ACTINST
11:55:06,174 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM4_HIST_PROCINST
11:55:06,191 FIN | [BaseJbpmTestCase] === ending testTaskAssignmentHandler
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.171 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 53 seconds
[INFO] Finished at: Tue May 26 11:55:06 EDT 2009
[INFO] Final Memory: 57M/283M
[INFO] ------------------------------------------------------------------------
Recording test results