5:55 p.m.
See http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=ibm15,label=linux/...
Changes:
[tom.baeyens(a)jboss.com] devguide updates and subprocess images in userguide and
coordinates in example sources
[tom.baeyens(a)jboss.com] update the oracle qa lab username/password
[tom.baeyens(a)jboss.com] update the oracle qa lab username/password
------------------------------------------
[...truncated 2563 lines...]
11:55:11,533 FIN | [BaseJbpmTestCase] === ending testTemplateMail
=============================
11:55:11,595 INF | [SMTPServer] SMTP Server socket shut down.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.699 sec
Running org.jbpm.examples.eventlistener.EventListenerTest
11:55:11,604 FIN | [BaseJbpmTestCase] === starting testEventListener
=============================
11:55:11,675 FIN | [ProcessDefinitionImpl] creating new execution for process
'EventListener'
11:55:11,678 FIN | [DefaultIdGenerator] generated execution id EventListener.44
11:55:11,680 FIN | [TakeTransition] taking -->(wait)
11:55:11,686 FIN | [Signal] signalling activity(wait), signal=null
11:55:11,690 FIN | [TakeTransition] taking (wait)-->(end)
11:55:11,692 FIN | [Execution] process-instance ends with state ended
11:55:11,697 FIN | [HibernatePvmDbSession] deleting process instance EventListener.44
11:55:11,717 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_ACTINST
11:55:11,719 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:55:11,727 FIN | [BaseJbpmTestCase] === ending testEventListener
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.131 sec
Running org.jbpm.examples.subprocess.outcomevalue.SubProcessOutcomeValueTest
11:55:11,745 FIN | [BaseJbpmTestCase] === starting testSubProcessResultOk
=============================
11:55:11,839 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:11,840 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.45
11:55:11,841 FIN | [TakeTransition] taking -->(review)
11:55:11,891 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:11,892 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.46
11:55:11,893 FIN | [TakeTransition] taking -->(get approval)
11:55:11,894 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:11,894 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:11,897 FIN | [TaskQueryImpl] select distinct task from
org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by
task.priority desc
11:55:11,898 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:11,900 FIN | [ScopeInstanceImpl] create variable 'result' in
'process-instance' with value 'ok'
11:55:11,905 FIN | [Signal] signalling activity(get approval), signal=null
11:55:11,907 FIN | [TakeTransition] taking (get approval)-->(end)
11:55:11,908 FIN | [Execution] process-instance ends with state ended
11:55:11,910 FIN | [Signal] signalling activity(review), signal=null
11:55:11,911 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:11,914 FIN | [TakeTransition] taking (review)--ok-->(next step)
11:55:11,918 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.46
11:55:11,938 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.45
11:55:11,941 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:11,942 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_PROCINST
11:55:11,946 FIN | [BaseJbpmTestCase] === ending testSubProcessResultOk
=============================
11:55:11,947 FIN | [BaseJbpmTestCase] === starting testSubProcessResultNok
=============================
11:55:11,979 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:11,980 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.47
11:55:11,981 FIN | [TakeTransition] taking -->(review)
11:55:11,983 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:11,984 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.48
11:55:11,985 FIN | [TakeTransition] taking -->(get approval)
11:55:11,986 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:11,986 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:11,989 FIN | [TaskQueryImpl] select distinct task from
org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by
task.priority desc
11:55:11,990 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:11,992 FIN | [ScopeInstanceImpl] create variable 'result' in
'process-instance' with value 'nok'
11:55:11,997 FIN | [Signal] signalling activity(get approval), signal=null
11:55:11,999 FIN | [TakeTransition] taking (get approval)-->(end)
11:55:12,000 FIN | [Execution] process-instance ends with state ended
11:55:12,003 FIN | [Signal] signalling activity(review), signal=null
11:55:12,004 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:12,006 FIN | [TakeTransition] taking (review)--nok-->(update)
11:55:12,012 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.48
11:55:12,081 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.47
11:55:12,085 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:12,086 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_PROCINST
11:55:12,091 FIN | [BaseJbpmTestCase] === ending testSubProcessResultNok
=============================
11:55:12,092 FIN | [BaseJbpmTestCase] === starting testSubProcessResultReject
=============================
11:55:12,287 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:12,288 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.49
11:55:12,289 FIN | [TakeTransition] taking -->(review)
11:55:12,291 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:12,292 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.50
11:55:12,293 FIN | [TakeTransition] taking -->(get approval)
11:55:12,294 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:12,294 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:12,297 FIN | [TaskQueryImpl] select distinct task from
org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by
task.priority desc
11:55:12,298 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:12,300 FIN | [ScopeInstanceImpl] create variable 'result' in
'process-instance' with value 'reject'
11:55:12,305 FIN | [Signal] signalling activity(get approval), signal=null
11:55:12,307 FIN | [TakeTransition] taking (get approval)-->(end)
11:55:12,308 FIN | [Execution] process-instance ends with state ended
11:55:12,310 FIN | [Signal] signalling activity(review), signal=null
11:55:12,311 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:12,313 FIN | [TakeTransition] taking (review)--reject-->(close)
11:55:12,317 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.50
11:55:12,337 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.49
11:55:12,340 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:12,341 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_PROCINST
11:55:12,345 FIN | [BaseJbpmTestCase] === ending testSubProcessResultReject
=============================
Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.607 sec
Running org.jbpm.examples.hql.HqlTest
11:55:12,353 FIN | [BaseJbpmTestCase] === starting testHql =============================
11:55:12,385 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:12,386 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:12,388 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:12,389 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:12,391 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:12,391 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:12,397 FIN | [ProcessDefinitionImpl] creating new execution for process
'Hql'
11:55:12,398 FIN | [DefaultIdGenerator] generated execution id Hql.51
11:55:12,399 FIN | [TakeTransition] taking -->(get task names)
11:55:12,401 FIN | [ScopeInstanceImpl] create variable 'tasknames with i' in
'process-instance' with value '[dishes, iron]'
11:55:12,403 FIN | [TakeTransition] taking (get task names)-->(count tasks)
11:55:12,406 FIN | [ScopeInstanceImpl] create variable 'tasks' in
'process-instance' with value '3'
11:55:12,407 FIN | [TakeTransition] taking (count tasks)-->(wait)
11:55:12,422 FIN | [HibernatePvmDbSession] deleting process instance Hql.51
11:55:12,431 SEV | [Db] FIXME: JBPM-2004 3 records left in table JBPM_HIST_ACTINST
11:55:12,432 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:55:12,486 FIN | [BaseJbpmTestCase] === ending testHql =============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.173 sec
Running org.jbpm.examples.subprocess.variables.SubProcessVariablesTest
11:55:12,552 FIN | [BaseJbpmTestCase] === starting testWaitStatesSequence
=============================
11:55:12,591 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessDocument'
11:55:12,592 FIN | [DefaultIdGenerator] generated execution id SubProcessDocument.52
11:55:12,592 FIN | [ScopeInstanceImpl] create variable 'document' in
'process-instance' with value 'This document describes how we can make more
money...'
11:55:12,593 FIN | [TakeTransition] taking -->(review)
11:55:12,595 FIN | [ProcessDefinitionImpl] creating new execution for process
'SubProcessReview'
11:55:12,597 FIN | [DefaultIdGenerator] generated execution id SubProcessReview.53
11:55:12,598 FIN | [ScopeInstanceImpl] create variable 'document' in
'process-instance' with value 'This document describes how we can make more
money...'
11:55:12,598 FIN | [TakeTransition] taking -->(get approval)
11:55:12,599 FIN | [ProcessDefinitionImpl] creating new execution for process
'TaskLifeCycle'
11:55:12,599 FIN | [ScopeInstanceImpl] create variable 'task' in
'process-instance' with value 'Task(null)'
11:55:12,604 FIN | [TaskQueryImpl] select distinct task from
org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by
task.priority desc
11:55:12,605 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:55:12,609 FIN | [ScopeInstanceImpl] create variable 'result' in
'process-instance' with value 'accept'
11:55:12,613 FIN | [Signal] signalling activity(get approval), signal=null
11:55:12,615 FIN | [TakeTransition] taking (get approval)-->(end)
11:55:12,616 FIN | [Execution] process-instance ends with state ended
11:55:12,618 FIN | [Signal] signalling activity(review), signal=null
11:55:12,619 WRN | [ProxyWarnLog] Narrowing proxy to class
org.jbpm.jpdl.internal.model.JpdlExecution - this operation breaks ==
11:55:12,622 FIN | [ScopeInstanceImpl] create variable 'reviewResult' in
'process-instance' with value 'accept'
11:55:12,623 FIN | [TakeTransition] taking (review)-->(wait)
11:55:12,627 FIN | [HibernatePvmDbSession] deleting process instance SubProcessReview.53
11:55:12,648 FIN | [HibernatePvmDbSession] deleting process instance
SubProcessDocument.52
11:55:12,651 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:12,652 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_PROCINST
11:55:12,656 FIN | [BaseJbpmTestCase] === ending testWaitStatesSequence
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.119 sec
Running org.jbpm.examples.script.text.ScriptTextTest
11:55:12,673 FIN | [BaseJbpmTestCase] === starting testScriptText
=============================
11:55:12,710 FIN | [ProcessDefinitionImpl] creating new execution for process
'ScriptText'
11:55:12,711 FIN | [DefaultIdGenerator] generated execution id ScriptText.54
11:55:12,712 FIN | [ScopeInstanceImpl] create variable 'person' in
'process-instance' with value
'org.jbpm.examples.script.text.Person@61d661d6'
11:55:12,713 FIN | [TakeTransition] taking -->(invoke script)
11:55:12,797 FIN | [ScopeInstanceImpl] create variable 'text' in
'process-instance' with value '
Send packet to Honolulu
'
11:55:12,798 FIN | [TakeTransition] taking (invoke script)-->(wait)
11:55:12,810 FIN | [HibernatePvmDbSession] deleting process instance ScriptText.54
11:55:12,814 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:12,814 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:55:12,819 FIN | [BaseJbpmTestCase] === ending testScriptText
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.153 sec
Running org.jbpm.examples.mail.inline.InlineMailTest
11:55:12,829 FIN | [BaseJbpmTestCase] === starting testInlineMail
=============================
11:55:12,916 FIN | [ProcessDefinitionImpl] creating new execution for process
'InlineMail'
11:55:12,917 FIN | [DefaultIdGenerator] generated execution id InlineMail.55
11:55:12,918 FIN | [ScopeInstanceImpl] create variable 'orderId' in
'process-instance' with value '14'
11:55:12,919 FIN | [TakeTransition] taking -->(send memo)
11:55:12,926 FIN | [TakeTransition] taking (send memo)-->(end)
11:55:12,937 FIN | [HibernatePvmDbSession] deleting process instance InlineMail.55
11:55:12,940 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:12,941 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:55:12,945 FIN | [BaseJbpmTestCase] === ending testInlineMail
=============================
11:55:12,947 INF | [SMTPServer] SMTP Server socket shut down.
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.337 sec
Running org.jbpm.examples.services.ServicesTest
11:55:13,209 INF | [Configuration] configuring from resource: jbpm.hibernate.cfg.xml
11:55:13,211 INF | [Configuration] Configuration resource: jbpm.hibernate.cfg.xml
11:55:13,218 INF | [Configuration] Reading mappings from resource :
jbpm.repository.hbm.xml
11:55:13,229 INF | [Configuration] Reading mappings from resource :
jbpm.execution.hbm.xml
11:55:13,247 INF | [Configuration] Reading mappings from resource : jbpm.history.hbm.xml
11:55:13,258 INF | [Configuration] Reading mappings from resource : jbpm.task.hbm.xml
11:55:13,270 INF | [Configuration] Reading mappings from resource : jbpm.jpdl.hbm.xml
11:55:13,278 INF | [Configuration] Reading mappings from resource : jbpm.identity.hbm.xml
11:55:13,288 INF | [Configuration] Configured SessionFactory: null
11:55:13,291 INF | [DriverManagerConnectionProvider] Using Hibernate built-in connection
pool (not for production use!)
11:55:13,292 INF | [DriverManagerConnectionProvider] Hibernate connection pool size: 20
11:55:13,293 INF | [DriverManagerConnectionProvider] autocommit mode: false
11:55:13,294 INF | [DriverManagerConnectionProvider] using driver: org.hsqldb.jdbcDriver
at URL: jdbc:hsqldb:mem:.
11:55:13,294 INF | [DriverManagerConnectionProvider] connection properties: {user=sa,
password=****}
11:55:13,295 INF | [Dialect] Using dialect: org.hibernate.dialect.HSQLDialect
11:55:13,297 INF | [TransactionFactoryFactory] Using default transaction strategy (direct
JDBC transactions)
11:55:13,298 INF | [TransactionManagerLookupFactory] No TransactionManagerLookup
configured (in JTA environment, use of read-write or transactional second-level cache is
not recommended)
11:55:13,299 INF | [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
11:55:13,304 INF | [SessionFactoryImpl] building session factory
11:55:13,550 INF | [SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name
configured
11:55:13,554 INF | [SchemaExport] Running hbm2ddl schema export
11:55:13,555 INF | [SchemaExport] exporting generated schema to database
11:55:13,561 INF | [SchemaExport] schema export complete
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.846 sec
Running org.jbpm.examples.java.JavaInstantiateTest
11:55:14,018 FIN | [BaseJbpmTestCase] === starting testJavaInstantiate
=============================
11:55:14,061 FIN | [ProcessDefinitionImpl] creating new execution for process
'Java'
11:55:14,062 FIN | [DefaultIdGenerator] generated execution id Java.1
11:55:14,063 FIN | [TakeTransition] taking -->(invoke java method)
11:55:14,065 FIN | [ScopeInstanceImpl] create variable 'answer' in
'process-instance' with value 'I'm fine, thank you.'
11:55:14,066 FIN | [TakeTransition] taking (invoke java method)-->(wait)
11:55:14,070 FIN | [HibernatePvmDbSession] deleting process instance Java.1
11:55:14,075 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:55:14,077 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:55:14,081 FIN | [BaseJbpmTestCase] === ending testJavaInstantiate
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.069 sec
Running org.jbpm.examples.timer.businesstime.TimerBusinessTimeTest
11:55:14,091 FIN | [BaseJbpmTestCase] === starting testBusinessTime
=============================
11:55:14,139 FIN | [ProcessDefinitionImpl] creating new execution for process
'TimerBusinessTime'
11:55:14,140 FIN | [DefaultIdGenerator] generated execution id TimerBusinessTime.2
11:55:14,141 FIN | [TakeTransition] taking -->(guardedWait)
11:55:14,142 FIN | [Execution] creating execution[guardedWait]
11:55:14,143 FIN | [DefaultIdGenerator] generated execution id
TimerBusinessTime.2.guardedWait[0]
11:55:14,143 FIN | [ScopeInstanceImpl] creating timer on execution[guardedWait]
11:55:14,145 FIN | [TimerSession] scheduling timer[0|2009-05-05 13:55:00,000|timeout]
11:55:14,156 FIN | [HibernatePvmDbSession] deleting process instance TimerBusinessTime.2
11:55:14,164 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_ACTINST
11:55:14,165 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:55:14,172 FIN | [BaseJbpmTestCase] === ending testBusinessTime
=============================
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.089 sec
Results :
Tests in error:
testSubProcessResultOk(org.jbpm.examples.subprocess.outcomeactivity.SubProcessOutcomeActivityTest)
testSubProcessResultNok(org.jbpm.examples.subprocess.outcomeactivity.SubProcessOutcomeActivityTest)
testSubProcessResultReject(org.jbpm.examples.subprocess.outcomeactivity.SubProcessOutcomeActivityTest)
Tests run: 48, Failures: 0, Errors: 3, Skipped: 0
[INFO] ------------------------------------------------------------------------
[ERROR] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] There are test failures.
Please refer to
/qa/services/hudson/hudson_workspace/workspace/jbpm4-base/jdk/ibm15/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 56 seconds
[INFO] Finished at: Mon May 04 11:55:14 EDT 2009
[INFO] Final Memory: 31M/99M
[INFO] ------------------------------------------------------------------------
Recording test results