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

jboss-qa-internal at redhat.com jboss-qa-internal at redhat.com
Mon May 4 11:53:45 EDT 2009 

See http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=ibm16,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 4336 lines...]
11:53:42,077 FIN | [Signal] execution[guardedWait] signals activity(guardedWait)
11:53:42,078 FIN | [TakeTransition] execution[guardedWait] takes (guardedWait)--go on-->(next step)
11:53:42,078 FIN | [ScopeInstanceImpl] destroying timers of execution[guardedWait]
11:53:42,079 FIN | [TimerSession] canceling timer[5|2009-05-04 11:53:52,068|timeout]
11:53:42,080 FIN | [Execution] execution[guardedWait] ends
11:53:42,094 FIN | [HibernatePvmDbSession] deleting process instance TimerRepeat.44
11:53:42,098 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:42,098 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,115 FIN | [BaseJbpmTestCase] === ending testTimerRepeat =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.122 sec
Running org.jbpm.examples.script.expression.ScriptExpressionTest
11:53:42,118 FIN | [BaseJbpmTestCase] === starting testScriptText =============================
11:53:42,163 FIN | [ProcessDefinitionImpl] creating new execution for process 'ScriptExpression'
11:53:42,163 FIN | [DefaultIdGenerator] generated execution id ScriptExpression.46
11:53:42,164 FIN | [ScopeInstanceImpl] create variable 'order' in 'process-instance' with value 'org.jbpm.examples.script.expression.Order at 6fb46fb4'
11:53:42,165 FIN | [TakeTransition] taking -->(invoke script)
11:53:42,171 FIN | [ScopeInstanceImpl] create variable 'text' in 'process-instance' with value 'Send packet to Berlin'
11:53:42,172 FIN | [TakeTransition] taking (invoke script)-->(wait)
11:53:42,187 FIN | [HibernatePvmDbSession] deleting process instance ScriptExpression.46
11:53:42,192 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:42,192 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,197 FIN | [BaseJbpmTestCase] === ending testScriptText =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.082 sec
Running org.jbpm.examples.timer.businesstime.TimerBusinessTimeTest
11:53:42,200 FIN | [BaseJbpmTestCase] === starting testBusinessTime =============================
11:53:42,241 FIN | [ProcessDefinitionImpl] creating new execution for process 'TimerBusinessTime'
11:53:42,242 FIN | [DefaultIdGenerator] generated execution id TimerBusinessTime.47
11:53:42,242 FIN | [TakeTransition] taking -->(guardedWait)
11:53:42,242 FIN | [Execution] creating execution[guardedWait]
11:53:42,242 FIN | [DefaultIdGenerator] generated execution id TimerBusinessTime.47.guardedWait[0]
11:53:42,242 FIN | [ScopeInstanceImpl] creating timer on execution[guardedWait]
11:53:42,244 FIN | [TimerSession] scheduling timer[0|2009-05-05 13:53:00,000|timeout]
11:53:42,272 FIN | [HibernatePvmDbSession] deleting process instance TimerBusinessTime.47
11:53:42,278 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_ACTINST
11:53:42,278 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,283 FIN | [BaseJbpmTestCase] === ending testBusinessTime =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.086 sec
Running org.jbpm.examples.task.assignmenthandler.TaskAssignmentHandlerTest
11:53:42,286 FIN | [BaseJbpmTestCase] === starting testTaskAssignmentHandler =============================
11:53:42,327 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskAssignmentHandler'
11:53:42,328 FIN | [DefaultIdGenerator] generated execution id TaskAssignmentHandler.49
11:53:42,328 FIN | [TakeTransition] taking -->(review)
11:53:42,328 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskLifeCycle'
11:53:42,328 FIN | [ScopeInstanceImpl] create variable 'task' in 'process-instance' with value 'Task(null)'
11:53:42,332 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by task.priority desc 
11:53:42,333 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:53:42,344 FIN | [HibernatePvmDbSession] deleting process instance TaskAssignmentHandler.49
11:53:42,348 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_ACTINST
11:53:42,349 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,353 FIN | [BaseJbpmTestCase] === ending testTaskAssignmentHandler =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.07 sec
Running org.jbpm.examples.state.choice.StateChoiceTest
11:53:42,356 FIN | [BaseJbpmTestCase] === starting testStateChoiceAccept =============================
11:53:42,409 FIN | [ProcessDefinitionImpl] creating new execution for process 'StateChoice'
11:53:42,410 FIN | [DefaultIdGenerator] generated execution id StateChoice.50
11:53:42,410 FIN | [TakeTransition] taking -->(wait for response)
11:53:42,413 FIN | [Signal] signalling activity(wait for response), signal=accept
11:53:42,414 FIN | [TakeTransition] taking (wait for response)--accept-->(submit document)
11:53:42,425 FIN | [HibernatePvmDbSession] deleting process instance StateChoice.50
11:53:42,429 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:42,429 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,434 FIN | [BaseJbpmTestCase] === ending testStateChoiceAccept =============================

11:53:42,434 FIN | [BaseJbpmTestCase] === starting testStateChoiceReject =============================
11:53:42,448 FIN | [ProcessDefinitionImpl] creating new execution for process 'StateChoice'
11:53:42,448 FIN | [DefaultIdGenerator] generated execution id StateChoice.51
11:53:42,449 FIN | [TakeTransition] taking -->(wait for response)
11:53:42,452 FIN | [Signal] signalling activity(wait for response), signal=reject
11:53:42,452 FIN | [TakeTransition] taking (wait for response)--reject-->(try again)
11:53:42,464 FIN | [HibernatePvmDbSession] deleting process instance StateChoice.51
11:53:42,467 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:42,468 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,472 FIN | [BaseJbpmTestCase] === ending testStateChoiceReject =============================

Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.119 sec
Running org.jbpm.examples.state.sequence.StateSequenceTest
11:53:42,475 FIN | [BaseJbpmTestCase] === starting testWaitStatesSequence =============================
11:53:42,527 FIN | [ProcessDefinitionImpl] creating new execution for process 'StateSequence'
11:53:42,528 FIN | [DefaultIdGenerator] generated execution id StateSequence.52
11:53:42,528 FIN | [TakeTransition] taking -->(a)
11:53:42,531 FIN | [Signal] signalling activity(a), signal=null
11:53:42,532 FIN | [TakeTransition] taking (a)-->(b)
11:53:42,535 FIN | [Signal] signalling activity(b), signal=null
11:53:42,535 FIN | [TakeTransition] taking (b)-->(c)
11:53:42,546 FIN | [HibernatePvmDbSession] deleting process instance StateSequence.52
11:53:42,550 SEV | [Db] FIXME: JBPM-2004 3 records left in table JBPM_HIST_ACTINST
11:53:42,550 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,555 FIN | [BaseJbpmTestCase] === ending testWaitStatesSequence =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.083 sec
Running org.jbpm.examples.sql.SqlTest
11:53:42,557 FIN | [BaseJbpmTestCase] === starting testSql =============================
11:53:42,595 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskLifeCycle'
11:53:42,595 FIN | [ScopeInstanceImpl] create variable 'task' in 'process-instance' with value 'Task(null)'
11:53:42,597 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskLifeCycle'
11:53:42,597 FIN | [ScopeInstanceImpl] create variable 'task' in 'process-instance' with value 'Task(null)'
11:53:42,599 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskLifeCycle'
11:53:42,599 FIN | [ScopeInstanceImpl] create variable 'task' in 'process-instance' with value 'Task(null)'
11:53:42,605 FIN | [ProcessDefinitionImpl] creating new execution for process 'Sql'
11:53:42,605 FIN | [DefaultIdGenerator] generated execution id Sql.53
11:53:42,606 FIN | [TakeTransition] taking -->(get task names)
11:53:42,609 FIN | [ScopeInstanceImpl] create variable 'tasknames with i' in 'process-instance' with value '[dishes, iron]'
11:53:42,610 FIN | [TakeTransition] taking (get task names)-->(count tasks)
11:53:42,611 FIN | [ScopeInstanceImpl] create variable 'tasks' in 'process-instance' with value '3'
11:53:42,612 FIN | [TakeTransition] taking (count tasks)-->(wait)
11:53:42,627 FIN | [HibernatePvmDbSession] deleting process instance Sql.53
11:53:42,636 SEV | [Db] FIXME: JBPM-2004 3 records left in table JBPM_HIST_ACTINST
11:53:42,636 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:42,641 FIN | [BaseJbpmTestCase] === ending testSql =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.086 sec
Running org.jbpm.examples.services.ServicesTest
11:53:42,683 INF | [Configuration] configuring from resource: jbpm.hibernate.cfg.xml
11:53:42,683 INF | [Configuration] Configuration resource: jbpm.hibernate.cfg.xml
11:53:42,688 INF | [Configuration] Reading mappings from resource : jbpm.repository.hbm.xml
11:53:42,705 INF | [Configuration] Reading mappings from resource : jbpm.execution.hbm.xml
11:53:42,739 INF | [Configuration] Reading mappings from resource : jbpm.history.hbm.xml
11:53:42,757 INF | [Configuration] Reading mappings from resource : jbpm.task.hbm.xml
11:53:42,779 INF | [Configuration] Reading mappings from resource : jbpm.jpdl.hbm.xml
11:53:42,792 INF | [Configuration] Reading mappings from resource : jbpm.identity.hbm.xml
11:53:42,807 INF | [Configuration] Configured SessionFactory: null
11:53:42,812 INF | [DriverManagerConnectionProvider] Using Hibernate built-in connection pool (not for production use!)
11:53:42,813 INF | [DriverManagerConnectionProvider] Hibernate connection pool size: 20
11:53:42,813 INF | [DriverManagerConnectionProvider] autocommit mode: false
11:53:42,814 INF | [DriverManagerConnectionProvider] using driver: org.hsqldb.jdbcDriver at URL: jdbc:hsqldb:mem:.
11:53:42,814 INF | [DriverManagerConnectionProvider] connection properties: {user=sa, password=****}
11:53:42,815 INF | [Dialect] Using dialect: org.hibernate.dialect.HSQLDialect
11:53:42,816 INF | [TransactionFactoryFactory] Using default transaction strategy (direct JDBC transactions)
11:53:42,816 INF | [TransactionManagerLookupFactory] No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
11:53:42,816 INF | [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
11:53:42,826 INF | [SessionFactoryImpl] building session factory
11:53:42,972 INF | [SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name configured
11:53:42,977 INF | [SchemaExport] Running hbm2ddl schema export
11:53:42,978 INF | [SchemaExport] exporting generated schema to database
11:53:42,990 INF | [SchemaExport] schema export complete
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.43 sec
Running org.jbpm.examples.task.assignee.TaskAssigneeTest
11:53:43,074 FIN | [BaseJbpmTestCase] === starting testTaskAssignee =============================
11:53:43,131 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskAssignee'
11:53:43,131 FIN | [DefaultIdGenerator] generated execution id TaskAssignee.1
11:53:43,131 FIN | [ScopeInstanceImpl] create variable 'order' in 'process-instance' with value 'org.jbpm.examples.task.assignee.Order at 4d264d26'
11:53:43,560 FIN | [TakeTransition] taking -->(review)
11:53:43,560 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskLifeCycle'
11:53:43,560 FIN | [ScopeInstanceImpl] create variable 'task' in 'process-instance' with value 'Task(null)'
11:53:43,567 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by task.priority desc 
11:53:43,567 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:53:43,570 FIN | [Signal] signalling activity(review), signal=null
11:53:43,573 FIN | [TakeTransition] taking (review)-->(wait)
11:53:43,575 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by task.priority desc 
11:53:43,575 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:53:43,586 FIN | [HibernatePvmDbSession] deleting process instance TaskAssignee.1
11:53:43,590 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:43,590 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:43,594 FIN | [BaseJbpmTestCase] === ending testTaskAssignee =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.523 sec
Running org.jbpm.examples.esb.EsbTest
11:53:43,602 FIN | [BaseJbpmTestCase] === starting testEsb =============================
11:53:43,675 FIN | [ProcessDefinitionImpl] creating new execution for process 'Esb'
11:53:43,675 FIN | [DefaultIdGenerator] generated execution id Esb.2
11:53:43,675 FIN | [ScopeInstanceImpl] create variable 'title' in 'process-instance' with value 'Internetworking with TCP/IP'
11:53:43,676 FIN | [TakeTransition] taking -->(invoke esb service)
log4j:WARN No appenders could be found for logger (org.jboss.soa.esb.services.registry.RegistryFactory).
log4j:WARN Please initialize the log4j system properly.
11:53:43,808 FIN | [EsbActivity] sending body: [ objects: {goal=deliver asap, bookTitle=Internetworking with TCP/IP} ] to service bookSold in category orderProcessing over the esb
11:53:43,812 FIN | [TakeTransition] taking (invoke esb service)-->(wait)
11:53:43,824 FIN | [HibernatePvmDbSession] deleting process instance Esb.2
11:53:43,827 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:43,827 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:43,831 FIN | [BaseJbpmTestCase] === ending testEsb =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.237 sec
Running org.jbpm.examples.mail.template.TemplateMailTest
11:53:43,834 FIN | [BaseJbpmTestCase] === starting testTemplateMail =============================
11:53:43,873 FIN | [ProcessDefinitionImpl] creating new execution for process 'TemplateMail'
11:53:43,873 FIN | [DefaultIdGenerator] generated execution id TemplateMail.3
11:53:43,873 FIN | [ScopeInstanceImpl] create variable 'orderId' in 'process-instance' with value '772'
11:53:43,874 FIN | [TakeTransition] taking -->(send memo)
11:53:43,883 FIN | [TakeTransition] taking (send memo)-->(end)
11:53:43,887 INF   | [SMTPServer] SMTP Server socket shut down.
11:53:43,896 FIN | [HibernatePvmDbSession] deleting process instance TemplateMail.3
11:53:43,900 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:43,900 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:43,905 FIN | [BaseJbpmTestCase] === ending testTemplateMail =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.088 sec
Running org.jbpm.examples.task.variables.TaskVariablesTest
11:53:43,922 FIN | [BaseJbpmTestCase] === starting testTaskAssignee =============================
11:53:43,960 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskVariables'
11:53:43,961 FIN | [DefaultIdGenerator] generated execution id TaskVariables.4
11:53:43,961 FIN | [ScopeInstanceImpl] create variable 'dollars' in 'process-instance' with value '100000'
11:53:43,961 FIN | [ScopeInstanceImpl] create variable 'category' in 'process-instance' with value 'big'
11:53:43,961 FIN | [TakeTransition] taking -->(review)
11:53:43,962 FIN | [ProcessDefinitionImpl] creating new execution for process 'TaskLifeCycle'
11:53:43,962 FIN | [ScopeInstanceImpl] create variable 'task' in 'process-instance' with value 'Task(null)'
11:53:43,965 FIN | [TaskQueryImpl] select distinct task from org.jbpm.pvm.internal.task.TaskImpl as task where task.assignee = :assignee order by task.priority desc 
11:53:43,965 FIN | [TaskQueryImpl] setting parameter assignee: johndoe
11:53:43,975 FIN | [ScopeInstanceImpl] create variable 'lires' in 'process-instance' with value '923874893'
11:53:43,976 FIN | [ScopeInstanceImpl] updating variable 'category' in 'process-instance' to value 'small'
11:53:43,988 FIN | [HibernatePvmDbSession] deleting process instance TaskVariables.4
11:53:43,992 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_ACTINST
11:53:43,992 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:43,996 FIN | [BaseJbpmTestCase] === ending testTaskAssignee =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.077 sec
Running org.jbpm.examples.script.text.ScriptTextTest
11:53:43,999 FIN | [BaseJbpmTestCase] === starting testScriptText =============================
11:53:44,037 FIN | [ProcessDefinitionImpl] creating new execution for process 'ScriptText'
11:53:44,037 FIN | [DefaultIdGenerator] generated execution id ScriptText.5
11:53:44,037 FIN | [ScopeInstanceImpl] create variable 'person' in 'process-instance' with value 'org.jbpm.examples.script.text.Person at 76587658'
11:53:44,039 FIN | [TakeTransition] taking -->(invoke script)
11:53:44,043 FIN | [ScopeInstanceImpl] create variable 'text' in 'process-instance' with value '
      Send packet to Honolulu
    '
11:53:44,043 FIN | [TakeTransition] taking (invoke script)-->(wait)
11:53:44,056 FIN | [HibernatePvmDbSession] deleting process instance ScriptText.5
11:53:44,059 SEV | [Db] FIXME: JBPM-2004 2 records left in table JBPM_HIST_ACTINST
11:53:44,059 SEV | [Db] FIXME: JBPM-2004 1 records left in table JBPM_HIST_PROCINST
11:53:44,063 FIN | [BaseJbpmTestCase] === ending testScriptText =============================

Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.081 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 http://hudson.qa.jboss.com/hudson/job/jbpm4-base/./jdk=ibm16,label=linux/ws/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 1 second
[INFO] Finished at: Mon May 04 11:53:44 EDT 2009
[INFO] Final Memory: 102M/182M
[INFO] ------------------------------------------------------------------------
Recording test results

More information about the jbpm-dev mailing list