[jbpm-dev] Build failed in Hudson: jbpm4-base » ibm15,linux #189

jboss-qa-internal at redhat.com jboss-qa-internal at redhat.com
Mon May 4 11:55:26 EDT 2009


See http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=ibm15,label=linux/189/changes

Changes:

[tom.baeyens at jboss.com] devguide updates and subprocess images in userguide and coordinates in example sources

[tom.baeyens at jboss.com] update the oracle qa lab username/password

[tom.baeyens at 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 at 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




More information about the jbpm-dev mailing list