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