Build failed in Hudson: drools #1748
by jboss-qa-internal@redhat.com
See http://hudson.qa.jboss.com/hudson/job/drools/1748/changes
Changes:
[mark.proctor(a)jboss.com] JBRULES-1667 Internal Models are not being resolved from the RuleBase CompositeClassLoader
-now iterate each classloader's parent in the array calling Class.forName on it
-We keep a separate, unique, parent map for this as it's likely many share the same parent.
------------------------------------------
[...truncated 25494 lines...]
EqualityKey int:1 int:2
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:2 long:2
Cheese( type='stilton', price=15 )
RightTuples Start
RightTuple int:11
RightTuple int:14
RightTuples END
Object : int:3 long:4
Cheese( type='stilton', price=30 )
RightTuples Start
RightTuple int:14
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuples End
EqualityKey int:1 int:2
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:1 long:1
org.drools.FactA@704f9693
RightTuples Start
RightTuple int:17
RightTuples END
Object : int:3 long:4
org.drools.FactC@70cf9693
RightTuples Start
RightTuple int:10
RightTuple int:20
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuple int:10 int:1
LeftTuples End
EqualityKey int:1 int:1
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:1 long:1
org.drools.FactA@704f9693
RightTuples Start
RightTuple int:17
RightTuples END
Object : int:3 long:4
org.drools.FactC@70cf9693
RightTuples Start
RightTuple int:10
RightTuple int:20
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuple int:10 int:1
LeftTuples End
EqualityKey int:1 int:1
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:1 long:1
Cheese( type='stilton', price=10 )
RightTuples Start
RightTuples END
Object : int:2 long:2
Cheese( type='stilton', price=15 )
RightTuples Start
RightTuple int:11
RightTuples END
Object : int:3 long:3
Cheese( type='stilton', price=20 )
RightTuples Start
RightTuple int:15
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuple int:11 int:1
LeftTuples End
EqualityKey int:1 int:1
EqualityKey int:1 int:2
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:1 long:1
Cheese( type='stilton', price=10 )
RightTuples Start
RightTuples END
Object : int:2 long:2
Cheese( type='stilton', price=15 )
RightTuples Start
RightTuple int:11
RightTuples END
Object : int:3 long:3
Cheese( type='stilton', price=20 )
RightTuples Start
RightTuple int:15
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuple int:11 int:1
LeftTuples End
EqualityKey int:1 int:1
EqualityKey int:1 int:2
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:1 long:1
Cheese( type='stilton', price=10 )
RightTuples Start
RightTuples END
Object : int:3 long:3
Cheese( type='stilton', price=20 )
RightTuples Start
RightTuple int:21
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuple int:17 int:1
JoinNode
RightTuple int:22 int:3
RuleTerminalNode
LeftTuples End
Rule org.drools.dynamic not node
AgendaItem long:21
ActivationGroup bool:false
AgendaItem bool:false
EqualityKey int:1 int:1
EqualityKey int:1 int:3
InitialFact int:0 long:0
InitialFactMemory false
Object : int:1 long:1
Cheese( type='stilton', price=10 )
RightTuples Start
RightTuples END
Object : int:3 long:3
Cheese( type='stilton', price=20 )
RightTuples Start
RightTuple int:21
RightTuples END
InitialFact LeftTuples Start
InitialFact LeftTuples End
LeftTuples Start
LeftTuple int:17 int:1
JoinNode
RightTuple int:22 int:3
RuleTerminalNode
LeftTuples End
Rule org.drools.dynamic not node
AgendaItem long:21
ActivationGroup bool:false
AgendaItem bool:false
EqualityKey int:1 int:1
EqualityKey int:1 int:3
Tests run: 17, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.774 sec
Running org.drools.guvnor.modeldriven.ActionSetFieldTest
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 sec
Running org.drools.guvnor.server.util.BRLPersitenceTest
<rule>
<modelVersion>1.0</modelVersion>
<attributes>
<attribute>
<attributeName>no-loop</attributeName>
<value>true</value>
</attribute>
</attributes>
<lhs>
<fact>
<constraintList>
<constraints>
<fieldConstraint>
<fieldBinding>f1</fieldBinding>
<fieldName>age</fieldName>
<operator><</operator>
<value>42</value>
<constraintValueType>0</constraintValueType>
</fieldConstraint>
</constraints>
</constraintList>
<factType>Person</factType>
<boundName>p1</boundName>
</fact>
<compositePattern>
<type>not</type>
<patterns>
<fact>
<factType>Cancel</factType>
</fact>
</patterns>
</compositePattern>
</lhs>
<rhs>
<modify>
<variable>p1</variable>
<fieldValues>
<fieldValue>
<field>status</field>
<value>rejected</value>
<type>String</type>
</fieldValue>
</fieldValues>
</modify>
<retract>
<variableName>p1</variableName>
</retract>
<dslSentence>
<sentence>Send an email to {administrator}</sentence>
</dslSentence>
</rhs>
</rule>
Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.065 sec
Running org.drools.testframework.RuleCoverageListenerTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.009 sec
Running org.drools.guvnor.modeldriven.CompositeFactPatternTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.005 sec
Running org.drools.rule.builder.dialect.mvel.MVELPredicateBuilderTest
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.016 sec
Results :
Tests in error:
testMockDialect(org.drools.compiler.PackageBuilderConfigurationTest)
Tests run: 775, 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/drools/trunk/drools-compiler/target/surefire-reports for the individual test results.
[INFO] ------------------------------------------------------------------------
[INFO] For more information, run Maven with the -e switch
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 4 minutes 12 seconds
[INFO] Finished at: Sat Jul 05 10:21:39 EDT 2008
[INFO] Final Memory: 24M/73M
[INFO] ------------------------------------------------------------------------
Terminating xvnc
[drools] $ vncserver -kill :38
Killing Xvnc process ID 6215
ERROR: No artifacts found that match the file pattern "trunk/target/,rules-ouput/". Configuration error?
ERROR: 'trunk/target/' doesn't match anything, but '' does. Perhaps that's what you mean?
Recording test results
16 years, 4 months
Build failed in Hudson: jboss-esb-linux #1026
by jboss-qa-internal@redhat.com
See http://hudson.qa.jboss.com/hudson/job/jboss-esb-linux/1026/changes
Changes:
[tcunning] bug:JBESB-1654
Add code to track byte size of message at serialization time for monitoring.
------------------------------------------
[...truncated 6182 lines...]
[java] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.349 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.aggregation.Nested_Splits_UnitTest
[java] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 1.349 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: test_nested_split took 1.089 sec
[java] [junit] Testcase: test_splitIds took 0.064 sec
[java] [junit] Running org.jboss.soa.esb.actions.converters.ByteArrayToStringUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.493 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.converters.ByteArrayToStringUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.493 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: test took 0.32 sec
[java] [junit] Running org.jboss.soa.esb.actions.converters.FileToStreamUnitTest
[java] [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.489 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.converters.FileToStreamUnitTest
[java] [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.489 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: unknown took 0.245 sec
[java] [junit] Testcase: unknown took 0 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Running org.jboss.soa.esb.actions.converters.ObjectToCSVStringUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.178 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.converters.ObjectToCSVStringUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.178 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: test took 0.159 sec
[java] [junit] Running org.jboss.soa.esb.actions.converters.ObjectToXStreamUnitTest
[java] [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.568 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.converters.ObjectToXStreamUnitTest
[java] [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.568 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: test_default took 0.491 sec
[java] [junit] Testcase: test_with_package took 0.006 sec
[java] [junit] Testcase: test_with_alias took 0.01 sec
[java] [junit] Testcase: test_with_namespace took 0.051 sec
[java] [junit] Running org.jboss.soa.esb.actions.converters.XStreamToObjectUnitTest
[java] [junit] Tests run: 16, Failures: 0, Errors: 0, Time elapsed: 1.081 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.converters.XStreamToObjectUnitTest
[java] [junit] Tests run: 16, Failures: 0, Errors: 0, Time elapsed: 1.081 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: unknown took 0.093 sec
[java] [junit] Testcase: unknown took 0.29 sec
[java] [junit] Testcase: unknown took 0.011 sec
[java] [junit] Testcase: unknown took 0.01 sec
[java] [junit] Testcase: unknown took 0.006 sec
[java] [junit] Testcase: unknown took 0.006 sec
[java] [junit] Testcase: unknown took 0.01 sec
[java] [junit] Testcase: unknown took 0.003 sec
[java] [junit] Testcase: unknown took 0.071 sec
[java] [junit] Testcase: unknown took 0.033 sec
[java] [junit] Testcase: unknown took 0.02 sec
[java] [junit] Testcase: unknown took 0.015 sec
[java] [junit] Testcase: unknown took 0.012 sec
[java] [junit] Testcase: unknown took 0.013 sec
[java] [junit] Testcase: unknown took 0.049 sec
[java] [junit] Testcase: unknown took 0.012 sec
[java] [junit] Running org.jboss.soa.esb.actions.naming.FileNameGeneratorActionUnitTest
[java] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.129 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.naming.FileNameGeneratorActionUnitTest
[java] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.129 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: testGetters took 0.05 sec
[java] [junit] Testcase: testStrategies took 0.071 sec
[java] [junit] Running org.jboss.soa.esb.actions.naming.strategy.AddTimestampNamingStrategyUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.134 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.naming.strategy.AddTimestampNamingStrategyUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.134 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: testProcess took 0.124 sec
[java] [junit] Running org.jboss.soa.esb.actions.naming.strategy.ChangeSuffixNamingStrategyUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.147 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.naming.strategy.ChangeSuffixNamingStrategyUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.147 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: testProcess took 0.137 sec
[java] [junit] Running org.jboss.soa.esb.actions.routing.EchoRouterUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.478 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.routing.EchoRouterUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.478 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: testRouter took 0.312 sec
[java] [junit] Running org.jboss.soa.esb.actions.routing.HttpRouterUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.751 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.routing.HttpRouterUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.751 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: unknown took 0.166 sec
[java] [junit] Running org.jboss.soa.esb.actions.routing.JmsRouterIntegrationTest
[java] [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 2.439 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.routing.JmsRouterIntegrationTest
[java] [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 2.439 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: unknown took 0.291 sec
[java] [junit] Testcase: unknown took 0.005 sec
[java] [junit] Testcase: unknown took 0.003 sec
[java] [junit] Testcase: unknown took 0.003 sec
[java] [junit] Testcase: unknown took 0.005 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 1.579 sec
[java] [junit] Testcase: unknown took 0.016 sec
[java] [junit] Testcase: unknown took 0.004 sec
[java] [junit] Testcase: unknown took 0.009 sec
[java] [junit] Testcase: unknown took 0.008 sec
[java] [junit] Testcase: unknown took 0.007 sec
[java] [junit] Testcase: unknown took 0.005 sec
[java] [junit] Testcase: unknown took 0.127 sec
[java] [junit] Running org.jboss.soa.esb.actions.scripting.GroovyActionProcessorUnitTest
[java] [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 1.505 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.scripting.GroovyActionProcessorUnitTest
[java] [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 1.505 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: test_script_file took 0.996 sec
[java] [junit] Testcase: test_script_caching_on took 0.044 sec
[java] [junit] Testcase: test_script_caching_off took 0.176 sec
[java] [junit] Testcase: test_script_inlined_fail took 0 sec
[java] [junit] Testcase: test_script_inlined_success took 0.048 sec
[java] [junit] Running org.jboss.soa.esb.actions.templates.MockActionUnitTest
[java] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.591 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.templates.MockActionUnitTest
[java] [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.591 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: testMockAction took 0.415 sec
[java] [junit] Testcase: testMockComposer took 0.008 sec
[java] [junit] Running org.jboss.soa.esb.actions.templating.StringTemplateProcessorUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.633 sec
[java] [junit] Testsuite: org.jboss.soa.esb.actions.templating.StringTemplateProcessorUnitTest
[java] [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.633 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: testProcessor took 0.402 sec
[java] [junit] Running org.jboss.soa.esb.addressing.eprs.JMSEprUnitTest
[java] [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 0.505 sec
[java] [junit] Testsuite: org.jboss.soa.esb.addressing.eprs.JMSEprUnitTest
[java] [junit] Tests run: 14, Failures: 0, Errors: 0, Time elapsed: 0.505 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: unknown took 0.228 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.001 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.001 sec
[java] [junit] Testcase: unknown took 0.003 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.002 sec
[java] [junit] Testcase: unknown took 0.005 sec
[java] [junit] Running org.jboss.soa.esb.addressing.eprs.LogicalEPRUnitTest
[java] [junit] Tests run: 4, Failures: 0, Errors: 1, Time elapsed: 0.71 sec
[java] [junit] Testsuite: org.jboss.soa.esb.addressing.eprs.LogicalEPRUnitTest
[java] [junit] Tests run: 4, Failures: 0, Errors: 1, Time elapsed: 0.71 sec
[java] [junit] ------------- Standard Output ---------------
[java] [junit] EMMA: collecting runtime coverage data ...
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: test_valid_uri took 0.302 sec
[java] [junit] Testcase: test_invalid_uri took 0.001 sec
[java] [junit] Testcase: test_Serialize_Deserialize took 0.205 sec
[java] [junit] Testcase: test_replyTo took 0.032 sec
[java] [junit] Caused an ERROR
[java] [junit] null
[java] [junit] java.lang.NumberFormatException: null
[java] [junit] at java.lang.Integer.parseInt(Integer.java:415)
[java] [junit] at java.lang.Integer.parseInt(Integer.java:497)
[java] [junit] at org.jboss.soa.esb.listeners.message.ActionStatusBean.<init>(ActionStatusBean.java:58)
[java] [junit] at org.jboss.soa.esb.listeners.message.ActionProcessingPipeline.process(ActionProcessingPipeline.java:367)
[java] [junit] at org.jboss.soa.esb.addressing.eprs.LogicalEPRUnitTest.test_replyTo(LogicalEPRUnitTest.java:187)
[java] BUILD FAILED
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/build.xml:173: The following error occurred while executing this line:
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/rosetta/build.xml:106: The following error occurred while executing this line:
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/rosetta/tests/build.xml:167: The following error occurred while executing this line:
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/rosetta/tests/build.xml:191: Test org.jboss.soa.esb.addressing.eprs.LogicalEPRUnitTest failed
[java] Total time: 2 minutes 29 seconds
[java] Java Result: 1
esb.init-app-server:
esb.init-esb-server:
esb.init-ftp-server:
esb.init:
stop-server:
[echo] Stopping local ESB server
[java] Shutdown message has been posted to the server.
[java] Server shutdown may take a while - check logfiles for completion
[echo] 2008-07-06 01:05:34 EDT: waiting for ESB server to stop, checking file /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/build/jbossesb-test/jbossesb-server/server/default/log/server.log ...
BUILD FAILED
/qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/integration-build.xml:11: The following error occurred while executing this line:
/qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/build.xml:488: Product Unit Test failure
Total time: 4 minutes 25 seconds
Recording test results
Recording Emma reports trunk/product/build/tests/coverage/coverage.xml
16 years, 4 months
JBoss-AS-5.0.x-TestSuite-sun15 - Build # 718 - Failure!
by jboss-qa-internal@redhat.com
JBoss-AS-5.0.x-TestSuite-sun15 - Build # 718 - Failure:
Check console output at https://hudson.jboss.org/hudson//job/JBoss-AS-5.0.x-TestSuite-sun15/718 to view the results.
Modifications since last build:
-------------------------------------------------------------------------
[...truncated 22529 lines...]
[junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 3.983 sec
[junit] Running org.jboss.test.jbossmessaging.test.JBossMessagingJoramUnitTestCase
[junit] Tests run: 198, Failures: 0, Errors: 0, Time elapsed: 39.49 sec
[junit] Running org.jboss.test.jbossmessaging.test.JBossSessionRecoverUnitTestCase
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 15.341 sec
[junit] Running org.jboss.test.jbossmessaging.test.MessageBodyUnitTestCase
[junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 11.713 sec
[junit] Running org.jboss.test.jbossmessaging.test.MessageTypesUnitTestCase
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 3.807 sec
[junit] Running org.jboss.test.jbossmessaging.test.RollBackUnitTestCase
[junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 7.475 sec
[junit] Running org.jboss.test.jbossmessaging.test.SecurityUnitTestCase
[junit] Tests run: 30, Failures: 0, Errors: 0, Time elapsed: 20.021 sec
[junit] Running org.jboss.test.jbossmessaging.test.TemporarySessionConnectionUnitTestCase
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 2.537 sec
[junit] Running org.jboss.test.jbossmessaging.test.UnackedUnitTestCase
[junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 36.76 sec
[junit] Running org.jboss.test.jbossmessaging.test.XAResourceUnitTestCase
[junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 3.278 sec
[junit] Running org.jboss.test.jbossmessaging.test.XAResourceWrapperSmokeUnitTestCase
[junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.556 sec
[server:stop] Shutting down server: jbossmessaging
[server:stop] Shutting down server: /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/shutdown.jar:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/client/jbossall-client.jar:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/client/jboss-common.jar org.jboss.Shutdown --server jnp://10.18.94.175:1099 --shutdown
[server:stop] shutdownTimeout will be=45
Overriding previous definition of reference to xerces.xerces.classpath
Overriding previous definition of reference to apache.commons.classpath
Overriding previous definition of reference to sax.classpath
Overriding previous definition of reference to xdoclet.task.classpath
Overriding previous definition of reference to gjt.jpl-util.classpath
Overriding previous definition of reference to opennms.joesnmp.classpath
Overriding previous definition of reference to jacorb.idl.classpath
Overriding previous definition of reference to sleepycat.classpath
Overriding previous definition of reference to apache.tomcat55.classpath
Overriding previous definition of reference to trove.classpath
Overriding previous definition of reference to qdox.classpath
Overriding previous definition of reference to javassist.classpath
Overriding previous definition of reference to hibernate3.classpath
Overriding previous definition of reference to odmg.classpath
Overriding previous definition of reference to cglib.classpath
Overriding previous definition of reference to ejb3-persistence.classpath
Overriding previous definition of reference to spring.classpath
Overriding previous definition of reference to apache.ant.classpath
Overriding previous definition of reference to jboss.test.classpath
tests-jbossmessaging-cluster:
[echo] creating jbm-cluster1 config
[copy] Copying 405 files to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jbm-cluster1
[copy] Copied 100 empty directories to 4 empty directories under /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jbm-cluster1
[echo] Overwriting config descriptors
[copy] Copying 1 file to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jbm-cluster1
[echo] creating jbm-cluster2 config
[copy] Copying 405 files to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jbm-cluster2
[copy] Copied 100 empty directories to 4 empty directories under /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jbm-cluster2
[echo] Overwriting config descriptors
[copy] Copying 1 file to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jbm-cluster2
[server:start] Starting server "jbm-cluster1" with command:
[server:start] /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/run.jar:/qa/tools/opt/jdk1.5.0_15/lib/tools.jar -Xms128m -Xmx512m -XX:MaxPermSize=512m -XX:+HeapDumpOnOutOfMemoryError -Djava.endorsed.dirs=/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/lib/endorsed -Djava.net.preferIPv4Stack=true org.jboss.Main -c jbm-cluster1 -b 10.18.94.175 -u 227.43.0.134
[server:start] Starting server "jbm-cluster2" with command:
[server:start] /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/run.jar:/qa/tools/opt/jdk1.5.0_15/lib/tools.jar -Xms128m -Xmx512m -XX:MaxPermSize=512m -XX:+HeapDumpOnOutOfMemoryError -Djava.net.preferIPv4Stack=true -Djbossnode0=10.18.94.175 -Djava.endorsed.dirs=/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/lib/endorsed -Djava.net.preferIPv4Stack=true -Djboss.messaging.ServerPeerID=1 org.jboss.Main -c jbm-cluster2 -b 10.18.94.176 -u 227.43.0.134
Overriding previous definition of reference to xerces.xerces.classpath
Overriding previous definition of reference to apache.commons.classpath
Overriding previous definition of reference to sax.classpath
Overriding previous definition of reference to xdoclet.task.classpath
Overriding previous definition of reference to gjt.jpl-util.classpath
Overriding previous definition of reference to opennms.joesnmp.classpath
Overriding previous definition of reference to jacorb.idl.classpath
Overriding previous definition of reference to sleepycat.classpath
Overriding previous definition of reference to apache.tomcat55.classpath
Overriding previous definition of reference to trove.classpath
Overriding previous definition of reference to qdox.classpath
Overriding previous definition of reference to javassist.classpath
Overriding previous definition of reference to hibernate3.classpath
Overriding previous definition of reference to odmg.classpath
Overriding previous definition of reference to cglib.classpath
Overriding previous definition of reference to ejb3-persistence.classpath
Overriding previous definition of reference to spring.classpath
Overriding previous definition of reference to apache.ant.classpath
Overriding previous definition of reference to jboss.test.classpath
exec-jbmcluster:
[junit] Running org.jboss.test.jbossmessaging.clustertest.ClusteredTestCase
[junit] Tests run: 6, Failures: 2, Errors: 0, Time elapsed: 119.639 sec
[junit] Test org.jboss.test.jbossmessaging.clustertest.ClusteredTestCase FAILED
[server:stop] Shutting down server: jbm-cluster1
[server:stop] Shutting down server: /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/shutdown.jar:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/client/jbossall-client.jar:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/client/jboss-common.jar org.jboss.Shutdown --server jnp://10.18.94.175:1099 --shutdown
[server:stop] shutdownTimeout will be=45
Overriding previous definition of reference to xerces.xerces.classpath
Overriding previous definition of reference to apache.commons.classpath
Overriding previous definition of reference to sax.classpath
Overriding previous definition of reference to xdoclet.task.classpath
Overriding previous definition of reference to gjt.jpl-util.classpath
Overriding previous definition of reference to opennms.joesnmp.classpath
Overriding previous definition of reference to jacorb.idl.classpath
Overriding previous definition of reference to sleepycat.classpath
Overriding previous definition of reference to apache.tomcat55.classpath
Overriding previous definition of reference to trove.classpath
Overriding previous definition of reference to qdox.classpath
Overriding previous definition of reference to javassist.classpath
Overriding previous definition of reference to hibernate3.classpath
Overriding previous definition of reference to odmg.classpath
Overriding previous definition of reference to cglib.classpath
Overriding previous definition of reference to ejb3-persistence.classpath
Overriding previous definition of reference to spring.classpath
Overriding previous definition of reference to apache.ant.classpath
Overriding previous definition of reference to jboss.test.classpath
init:
tests-compatibility:
[junit] Running org.jboss.test.compatibility.test.SerialVersionUIDUnitTestCase
[junit] Tests run: 2, Failures: 2, Errors: 0, Time elapsed: 92.104 sec
[junit] Test org.jboss.test.compatibility.test.SerialVersionUIDUnitTestCase FAILED
Overriding previous definition of reference to xerces.xerces.classpath
Overriding previous definition of reference to apache.commons.classpath
Overriding previous definition of reference to sax.classpath
Overriding previous definition of reference to xdoclet.task.classpath
Overriding previous definition of reference to gjt.jpl-util.classpath
Overriding previous definition of reference to opennms.joesnmp.classpath
Overriding previous definition of reference to jacorb.idl.classpath
Overriding previous definition of reference to sleepycat.classpath
Overriding previous definition of reference to apache.tomcat55.classpath
Overriding previous definition of reference to trove.classpath
Overriding previous definition of reference to qdox.classpath
Overriding previous definition of reference to javassist.classpath
Overriding previous definition of reference to hibernate3.classpath
Overriding previous definition of reference to odmg.classpath
Overriding previous definition of reference to cglib.classpath
Overriding previous definition of reference to ejb3-persistence.classpath
Overriding previous definition of reference to spring.classpath
Overriding previous definition of reference to apache.ant.classpath
Overriding previous definition of reference to jboss.test.classpath
tests-aop-scoped:
[copy] Copying 1 file to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin
[echo] creating scoped-aop-jdk50 config
[copy] Copying 405 files to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/scoped-aop-jdk50
[copy] Copied 100 empty directories to 4 empty directories under /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/scoped-aop-jdk50
[echo] Overwriting config descriptors
[copy] Warning: /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/testsuite/output/resources/test-configs/scoped-aop-jdk50 not found.
[server:start] Starting server "scoped-aop-jdk50" with command:
[server:start] /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/run.jar:/qa/tools/opt/jdk1.5.0_15/lib/tools.jar -Xms128m -Xmx512m -XX:MaxPermSize=512m -XX:+HeapDumpOnOutOfMemoryError -javaagent:pluggable-instrumentor.jar -Djava.net.preferIPv4Stack=true -Djava.endorsed.dirs=/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/lib/endorsed -Djgroups.udp.ip_ttl=0 org.jboss.Main -c scoped-aop-jdk50 -b 10.18.94.175 -u 227.43.0.134
Overriding previous definition of reference to xerces.xerces.classpath
Overriding previous definition of reference to apache.commons.classpath
Overriding previous definition of reference to sax.classpath
Overriding previous definition of reference to xdoclet.task.classpath
Overriding previous definition of reference to gjt.jpl-util.classpath
Overriding previous definition of reference to opennms.joesnmp.classpath
Overriding previous definition of reference to jacorb.idl.classpath
Overriding previous definition of reference to sleepycat.classpath
Overriding previous definition of reference to apache.tomcat55.classpath
Overriding previous definition of reference to trove.classpath
Overriding previous definition of reference to qdox.classpath
Overriding previous definition of reference to javassist.classpath
Overriding previous definition of reference to hibernate3.classpath
Overriding previous definition of reference to odmg.classpath
Overriding previous definition of reference to cglib.classpath
Overriding previous definition of reference to ejb3-persistence.classpath
Overriding previous definition of reference to spring.classpath
Overriding previous definition of reference to apache.ant.classpath
Overriding previous definition of reference to jboss.test.classpath
run-tests-aop-scoped:
[echo] Starting patternset=aop-with-classloader.includes config=aop-scoped
[junit] Running org.jboss.test.aop.test.ScopedAttachUnitTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedAttachUnitTestCase FAILED (timeout)
[junit] Running org.jboss.test.aop.test.ScopedEarWithClassesInWebInfTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedEarWithClassesInWebInfTestCase FAILED (timeout)
[junit] Running org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedNoParentDelegationTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedNoParentDelegationTestCase FAILED (timeout)
[junit] Running org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedParentDelegationTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedExtenderBaseLoadedScopedParentDelegationTestCase FAILED (timeout)
[junit] Running org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedNoParentDelegationTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedNoParentDelegationTestCase FAILED (timeout)
[junit] Running org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedParentDelegationTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedExtenderBaseNotLoadedScopedParentDelegationTestCase FAILED (timeout)
[junit] Running org.jboss.test.aop.test.ScopedUnitTestCase
[junit] Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 0 sec
[junit] Test org.jboss.test.aop.test.ScopedUnitTestCase FAILED (timeout)
[server:stop] Shutting down server: scoped-aop-jdk50
[server:stop] Shutting down server: /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/shutdown.jar:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/client/jbossall-client.jar:/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/client/jboss-common.jar org.jboss.Shutdown --server jnp://10.18.94.175:1099 --shutdown
[server:stop] shutdownTimeout will be=45
[server:stop] Failed to shutdown server "scoped-aop-jdk50" before timeout. Destroying the process.
[server:stop] Unable to shutdown server properly: org.jboss.jbossas.servermanager.ServerShutdownException: Failed to shutdown server before timeout. Process was destroyed.
[delete] Deleting: /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/pluggable-instrumentor.jar
Overriding previous definition of reference to xerces.xerces.classpath
Overriding previous definition of reference to apache.commons.classpath
Overriding previous definition of reference to sax.classpath
Overriding previous definition of reference to xdoclet.task.classpath
Overriding previous definition of reference to gjt.jpl-util.classpath
Overriding previous definition of reference to opennms.joesnmp.classpath
Overriding previous definition of reference to jacorb.idl.classpath
Overriding previous definition of reference to sleepycat.classpath
Overriding previous definition of reference to apache.tomcat55.classpath
Overriding previous definition of reference to trove.classpath
Overriding previous definition of reference to qdox.classpath
Overriding previous definition of reference to javassist.classpath
Overriding previous definition of reference to hibernate3.classpath
Overriding previous definition of reference to odmg.classpath
Overriding previous definition of reference to cglib.classpath
Overriding previous definition of reference to ejb3-persistence.classpath
Overriding previous definition of reference to spring.classpath
Overriding previous definition of reference to apache.ant.classpath
Overriding previous definition of reference to jboss.test.classpath
jrmp-invoker-tests:
[echo] creating jrmp-invoker config
[copy] Copying 366 files to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jrmp-invoker
[copy] Copied 81 empty directories to 2 empty directories under /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jrmp-invoker
[echo] Overwriting config descriptors
[copy] Copying 1 file to /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/server/jrmp-invoker
[server:start] Starting server "jrmp-invoker" with command:
[server:start] /qa/tools/opt/jdk1.5.0_15/bin/java -cp /qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/build/output/jboss-5.0.0.CR2/bin/run.jar:/qa/tools/opt/jdk1.5.0_15/lib/tools.jar -Xmx128m -Djava.net.preferIPv4Stack=true org.jboss.Main -c jrmp-invoker -b 10.18.94.175 -u 227.43.0.134
[server:start] Failed to start server "jrmp-invoker" before timeout. Destroying the process.
BUILD FAILED
/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/testsuite/build.xml:927: The following error occurred while executing this line:
/qa/services/hudson/hudson_workspace/workspace/JBoss-AS-5.0.x-TestSuite-sun15/trunk/testsuite/build.xml:1169: Error starting server "jrmp-invoker": Server failed to start; see logs.
Total time: 247 minutes 16 seconds
+ BSTATUS=1
+ [[ 1 -ne 0 ]]
+ kill-jboss
++ uname -s
+ [[ Linux == \L\i\n\u\x ]]
+ ps -eaf --columns 2200
+ grep run.jar
+ grep -v grep
+ awk '{ print $2; }'
+ xargs kill
+ sleep 10
+ ps -eaf --columns 2200
+ grep run.jar
+ grep -v grep
+ awk '{ print $2; }'
+ xargs kill -9
+ exit 1
[JBoss-AS-5.0.x-TestSuite-sun15] $ bash -xe /tmp/hudson32819.sh
+ kill 4395
Recording test results
Build was marked for publishing on https://hudson.jboss.org/hudson/
Email was triggered for: Failure
There are 1 triggered emails.
Sending email for trigger: Failure
16 years, 4 months
Build failed in Hudson: jboss-esb-linux #1018
by jboss-qa-internal@redhat.com
See http://hudson.qa.jboss.com/hudson/job/jboss-esb-linux/1018/changes
Changes:
[beve] Work for JBESB-1504 "CBR using rules has poor performance"
------------------------------------------
[...truncated 10011 lines...]
[java] [junit] 07:49:29,791 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'before-signal' on 'State(wait1)' for 'Token(/)'
[java] [junit] 07:49:29,791 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'node-leave' on 'State(wait1)' for 'Token(/)'
[java] [junit] 07:49:29,792 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] executing action 'CancelTimerAction(1a9bea3)'
[java] [junit] 07:49:29,792 DEBUG [JbpmJobExector:10.18.1.111:1][Token] token[1] is locked by token[1]
[java] [junit] 07:49:29,795 WARN [JbpmJobExector:10.18.1.111:1][ProxyWarnLog] Narrowing proxy to class org.jbpm.job.Timer - this operation breaks ==
[java] [junit] 07:49:29,795 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] deleting timer timer(wait1,08-07-01 07:49:26,958,Token: 1) by name wait1
[java] [junit] 07:49:29,797 DEBUG [JbpmJobExector:10.18.1.111:1][Token] token[1] is unlocked by token[1]
[java] [junit] 07:49:29,797 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'transition' on 'Transition(continue)' for 'Token(/)'
[java] [junit] 07:49:29,799 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'node-enter' on 'Node(mynode)' for 'Token(/)'
[java] [junit] 07:49:29,801 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'node-leave' on 'Node(mynode)' for 'Token(/)'
[java] [junit] 07:49:29,801 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'transition' on 'Transition(toWait2)' for 'Token(/)'
[java] [junit] 07:49:29,802 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'node-enter' on 'State(wait2)' for 'Token(/)'
[java] [junit] 07:49:29,803 WARN [JbpmJobExector:10.18.1.111:1][ProxyWarnLog] Narrowing proxy to class org.jbpm.graph.node.State - this operation breaks ==
[java] [junit] 07:49:29,805 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] executing action 'CreateTimerAction(fbcb70)'
[java] [junit] 07:49:29,805 DEBUG [JbpmJobExector:10.18.1.111:1][Token] token[1] is locked by token[1]
[java] [junit] 07:49:29,805 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'timer-create' on 'State(wait2)' for 'Token(/)'
[java] [junit] 07:49:29,806 DEBUG [JbpmJobExector:10.18.1.111:1][Token] token[1] is unlocked by token[1]
[java] [junit] 07:49:29,806 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'after-signal' on 'State(wait1)' for 'Token(/)'
[java] [junit] 07:49:29,806 DEBUG [JbpmJobExector:10.18.1.111:1][Services] executing default save operations
[java] [junit] 07:49:29,806 DEBUG [JbpmJobExector:10.18.1.111:1][HibernateSaveOperation] saving process instance
[java] [junit] 07:49:29,807 DEBUG [JbpmJobExector:10.18.1.111:1][SaveLogsOperation] flushing logs to logging service.
[java] [junit] 07:49:29,811 DEBUG [JbpmJobExector:10.18.1.111:1][CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@25b72a'
[java] [junit] 07:49:29,811 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] deleting timer(wait1,08-07-01 07:49:26,958,Token: 1)
[java] [junit] 07:49:29,811 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@537935
[java] [junit] 07:49:29,811 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@ca5bff
[java] [junit] 07:49:29,811 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@3aa791
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@150f0a7
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'scheduler': org.jbpm.scheduler.db.DbSchedulerService@e771f3
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'logging': org.jbpm.logging.db.DbLoggingService@ea3932
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] acquiring jobs for execution...
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] creating org.jbpm.JbpmContext@15118a7
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@1299f7e
[java] [junit] 07:49:29,815 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] querying for acquirable job...
[java] [junit] 07:49:29,816 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] no acquirable jobs in job table
[java] [junit] 07:49:29,816 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@15118a7
[java] [junit] 07:49:29,816 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@53a9d5
[java] [junit] 07:49:29,816 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@1299f7e
[java] [junit] 07:49:29,816 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:29,816 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@b2e752
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] obtained locks on following jobs: []
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] creating org.jbpm.JbpmContext@170ec24
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:29,817 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@9f3e95
[java] [junit] 07:49:29,819 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@170ec24
[java] [junit] 07:49:29,819 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@13c4c09
[java] [junit] 07:49:29,819 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@9f3e95
[java] [junit] 07:49:29,819 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:29,819 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@1a40247
[java] [junit] 07:49:29,986 DEBUG [main][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:29,986 DEBUG [main][JbpmContext] creating org.jbpm.JbpmContext@91a4fb
[java] [junit] 07:49:29,986 DEBUG [main][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:29,986 DEBUG [main][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:29,986 DEBUG [main][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:29,986 DEBUG [main][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@1e6978d
[java] [junit] 4 wait2
[java] [junit] 07:49:29,989 DEBUG [main][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@91a4fb
[java] [junit] 07:49:29,989 DEBUG [main][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@3c35fd
[java] [junit] 07:49:29,989 DEBUG [main][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@1e6978d
[java] [junit] 07:49:29,990 DEBUG [main][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:29,990 DEBUG [main][Services] closing service 'tx': org.jbpm.tx.TxService@1f7abae
[java] [junit] 07:49:30,992 DEBUG [main][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:30,992 DEBUG [main][JbpmContext] creating org.jbpm.JbpmContext@1286b10
[java] [junit] 07:49:30,992 DEBUG [main][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:30,992 DEBUG [main][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:30,992 DEBUG [main][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:30,992 DEBUG [main][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@8d2280
[java] [junit] 5 wait2
[java] [junit] 07:49:30,994 DEBUG [main][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@1286b10
[java] [junit] 07:49:30,994 DEBUG [main][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@41f227
[java] [junit] 07:49:30,994 DEBUG [main][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@8d2280
[java] [junit] 07:49:30,995 DEBUG [main][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:30,995 DEBUG [main][Services] closing service 'tx': org.jbpm.tx.TxService@24eafa
[java] [junit] 07:49:31,807 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] acquiring jobs for execution...
[java] [junit] 07:49:31,807 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:31,807 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] creating org.jbpm.JbpmContext@1bef987
[java] [junit] 07:49:31,807 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:31,807 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:31,807 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:31,808 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@f01d52
[java] [junit] 07:49:31,808 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] querying for acquirable job...
[java] [junit] 07:49:31,811 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] trying to obtain a lock for 'timer(wait2,08-07-01 07:49:31,805,Token: 1)'
[java] [junit] 07:49:31,811 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@1bef987
[java] [junit] 07:49:31,811 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@124614c
[java] [junit] 07:49:31,811 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@f01d52
[java] [junit] 07:49:31,812 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@15e00b7
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] obtained locks on following jobs: [timer(wait2,08-07-01 07:49:31,805,Token: 1)]
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] creating org.jbpm.JbpmContext@81018b
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:31,813 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@18c6cbc
[java] [junit] 07:49:31,815 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] executing job timer(wait2,08-07-01 07:49:31,805,Token: 1)
[java] [junit] 07:49:31,819 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'before-signal' on 'State(wait2)' for 'Token(/)'
[java] [junit] 07:49:31,819 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'node-leave' on 'State(wait2)' for 'Token(/)'
[java] [junit] 07:49:31,820 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] executing action 'CancelTimerAction(f1f34a)'
[java] [junit] 07:49:31,820 DEBUG [JbpmJobExector:10.18.1.111:1][Token] token[1] is locked by token[1]
[java] [junit] 07:49:31,823 WARN [JbpmJobExector:10.18.1.111:1][ProxyWarnLog] Narrowing proxy to class org.jbpm.job.Timer - this operation breaks ==
[java] [junit] 07:49:31,823 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] deleting timer timer(wait2,08-07-01 07:49:31,805,Token: 1) by name wait2
[java] [junit] 07:49:31,823 DEBUG [JbpmJobExector:10.18.1.111:1][Token] token[1] is unlocked by token[1]
[java] [junit] 07:49:31,823 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'transition' on 'Transition(continue)' for 'Token(/)'
[java] [junit] 07:49:31,825 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'node-enter' on 'EndState(end)' for 'Token(/)'
[java] [junit] 07:49:31,827 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'process-end' on 'ProcessDefinition(testTimer)' for 'Token(/)'
[java] [junit] 07:49:31,828 DEBUG [JbpmJobExector:10.18.1.111:1][GraphElement] event 'after-signal' on 'State(wait2)' for 'Token(/)'
[java] [junit] 07:49:31,828 DEBUG [JbpmJobExector:10.18.1.111:1][Services] executing default save operations
[java] [junit] 07:49:31,828 DEBUG [JbpmJobExector:10.18.1.111:1][HibernateSaveOperation] saving process instance
[java] [junit] 07:49:31,828 DEBUG [JbpmJobExector:10.18.1.111:1][SaveLogsOperation] flushing logs to logging service.
[java] [junit] 07:49:31,831 DEBUG [JbpmJobExector:10.18.1.111:1][CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@1de7497'
[java] [junit] 07:49:31,831 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] deleting timer(wait2,08-07-01 07:49:31,805,Token: 1)
[java] [junit] 07:49:31,831 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@81018b
[java] [junit] 07:49:31,831 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@e33af5
[java] [junit] 07:49:31,831 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@18c6cbc
[java] [junit] 07:49:31,834 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] deleting timers for process instance org.jbpm.graph.exe.ProcessInstance@1de7497
[java] [junit] 07:49:31,838 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] 0 remaining timers for 'org.jbpm.graph.exe.ProcessInstance@1de7497' are deleted
[java] [junit] 07:49:31,838 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] deleting execute-node-jobs for process instance org.jbpm.graph.exe.ProcessInstance@1de7497
[java] [junit] 07:49:31,840 DEBUG [JbpmJobExector:10.18.1.111:1][JobSession] 0 remaining execute-node-jobs for 'org.jbpm.graph.exe.ProcessInstance@1de7497' are deleted
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@c0890f
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'scheduler': org.jbpm.scheduler.db.DbSchedulerService@666d83
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'logging': org.jbpm.logging.db.DbLoggingService@9fd062
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] acquiring jobs for execution...
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] creating org.jbpm.JbpmContext@29f93b
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@1a998c7
[java] [junit] 07:49:31,842 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] querying for acquirable job...
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] no acquirable jobs in job table
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@29f93b
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@b28980
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@1a998c7
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@161509b
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][JobExecutorThread] obtained locks on following jobs: []
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:31,843 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] creating org.jbpm.JbpmContext@1f30035
[java] [junit] 07:49:31,844 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:31,844 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:31,844 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:31,844 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@92af24
[java] [junit] 07:49:31,845 DEBUG [JbpmJobExector:10.18.1.111:1][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@1f30035
[java] [junit] 07:49:31,845 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@6c9ec6
[java] [junit] 07:49:31,845 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@92af24
[java] [junit] 07:49:31,845 DEBUG [JbpmJobExector:10.18.1.111:1][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:31,845 DEBUG [JbpmJobExector:10.18.1.111:1][Services] closing service 'tx': org.jbpm.tx.TxService@12beeec
[java] [junit] 07:49:31,997 DEBUG [main][JbpmContextInfo] creating jbpm context with service factories '[tx, message, scheduler, logging, persistence, authentication]'
[java] [junit] 07:49:31,997 DEBUG [main][JbpmContext] creating org.jbpm.JbpmContext@152e961
[java] [junit] 07:49:31,997 DEBUG [main][DbPersistenceServiceFactory] creating persistence service
[java] [junit] 07:49:31,997 DEBUG [main][DbPersistenceService] creating hibernate session
[java] [junit] 07:49:31,997 DEBUG [main][DbPersistenceService] beginning hibernate transaction
[java] [junit] 07:49:31,998 DEBUG [main][DbPersistenceService] begun hibernate transaction org.hibernate.transaction.JDBCTransaction@57f57f
[java] [junit] 6 end
[java] [junit] 07:49:32,000 DEBUG [main][JbpmContext] closing jbpmContext org.jbpm.JbpmContext@152e961
[java] [junit] 07:49:32,000 DEBUG [main][Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@65394b
[java] [junit] 07:49:32,000 DEBUG [main][DbPersistenceService] committing hibernate transaction org.hibernate.transaction.JDBCTransaction@57f57f
[java] [junit] 07:49:32,000 DEBUG [main][DbPersistenceService] closing hibernate session
[java] [junit] 07:49:32,001 DEBUG [main][Services] closing service 'tx': org.jbpm.tx.TxService@5c1eae
[java] [junit] ------------- ---------------- ---------------
[java] [junit] Testcase: unknown took 7.116 sec
[java] [junitreport] Transform time: 232ms
[java] [junitreport] Transform time: 87ms
[java] [report] processing input files ...
[java] [report] 1 file(s) read and merged in 6 ms
[java] [report] writing [html] report to [/qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbpm/build/tests/coverage/index.html] ...
[java] test:
[java] test:
[java] [copy] Copying 10 files to /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/tests/classes
[java] prepare:
[java] [mkdir] Created dir: /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/jbrules.esb/META-INF
[java] [mkdir] Created dir: /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/classes
[java] compile:
[java] [javac] Compiling 15 source files to /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/classes
[java] [javac] Note: /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/src/main/java/org/jboss/internal/soa/esb/services/routing/cbr/DslHelper.java uses or overrides a deprecated API.
[java] [javac] Note: Recompile with -Xlint:deprecation for details.
[java] check.for.files:
[java] jar:
[java] [jar] Building jar: /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/jbossesb-jbrules.jar
[java] esb:
[java] [copy] Copying 17 files to /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/jbrules.esb
[java] base.test:
[java] [mkdir] Created dir: /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/tests/coverage/instr
[java] [instr] processing instrumentation path ...
[java] [instr] instrumentation path processed in 211 ms
[java] [instr] [18 class(es) instrumented, 0 resource(s) copied]
[java] [instr] metadata merged into [/qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/tests/coverage/coverage.emma] {in 17 ms}
[java] [mkdir] Created dir: /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/tests/report
[java] [javac] Compiling 11 source files to /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/tests/classes
[java] [copy] Copying 3 files to /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build/tests/classes
[java] [echo] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules
[java] [junit] Running org.jboss.internal.soa.esb.services.routing.cbr.DslHelperUnitTest
[java] [junit] Tests run: 1, Failures: 1, Errors: 0, Time elapsed: 0.235 sec
[java] [junit] Testsuite: org.jboss.internal.soa.esb.services.routing.cbr.DslHelperUnitTest
[java] [junit] Tests run: 1, Failures: 1, Errors: 0, Time elapsed: 0.235 sec
[java] [junit] Testcase: warning took 0.003 sec
[java] [junit] FAILED
[java] [junit] No tests found in org.jboss.internal.soa.esb.services.routing.cbr.DslHelperUnitTest
[java] [junit] junit.framework.AssertionFailedError: No tests found in org.jboss.internal.soa.esb.services.routing.cbr.DslHelperUnitTest
[java] BUILD FAILED
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/build.xml:178: The following error occurred while executing this line:
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/build.xml:30: The following error occurred while executing this line:
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/jbrules/build.xml:16: The following error occurred while executing this line:
[java] /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/services/base-project-build.xml:104: Test org.jboss.internal.soa.esb.services.routing.cbr.DslHelperUnitTest failed
[java] Total time: 7 minutes 17 seconds
[java] Java Result: 1
esb.init-app-server:
esb.init-esb-server:
esb.init-ftp-server:
esb.init:
stop-server:
[echo] Stopping local ESB server
[java] Shutdown message has been posted to the server.
[java] Server shutdown may take a while - check logfiles for completion
[echo] 2008-07-01 07:49:35 EDT: waiting for ESB server to stop, checking file /qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/build/jbossesb-test/jbossesb-server/server/default/log/server.log ...
BUILD FAILED
/qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/integration-build.xml:11: The following error occurred while executing this line:
/qa/services/hudson/hudson_workspace/workspace/jboss-esb-linux/trunk/product/build.xml:488: Product Unit Test failure
Total time: 7 minutes 53 seconds
Recording test results
Recording Emma reports trunk/product/build/tests/coverage/coverage.xml
16 years, 4 months