Re: [CI] Lucene Linux Java 8 64 Test Only - Build # 28585 - Failure!

2015-02-03 Thread Michael McCandless 
TestScorerPerf.testConjunctions timed out @ 2 hours ... but this does not
repro for me standalone, nor with master seed, using G1GC.  The test runs
very quickly...

Mike McCandless

http://blog.mikemccandless.com

On Tue, Feb 3, 2015 at 3:07 AM,  wrote:

>   *BUILD FAILURE*
> Build URL
> http://build-eu-00.elasticsearch.org/job/lucene_trunk_linux_java8_64_test_only/28585/
> Project:lucene_trunk_linux_java8_64_test_only Date of build:Tue, 03 Feb
> 2015 07:06:18 +0100 Build duration:2 hr 0 min
>  *CHANGES* No Changes
>  *BUILD ARTIFACTS*
> checkout/lucene/build/core/test/temp/junit4-J0-20150203_070621_306.events
> 
> checkout/lucene/build/core/test/temp/junit4-J1-20150203_070621_307.events
> 
> checkout/lucene/build/core/test/temp/junit4-J2-20150203_070621_307.events
> 
> checkout/lucene/build/core/test/temp/junit4-J3-20150203_070621_320.events
> 
> checkout/lucene/build/core/test/temp/junit4-J4-20150203_070621_318.events
> 
> checkout/lucene/build/core/test/temp/junit4-J5-20150203_070621_318.events
> 
> checkout/lucene/build/core/test/temp/junit4-J6-20150203_070621_320.events
> 
> checkout/lucene/build/core/test/temp/junit4-J7-20150203_070621_324.events
> 
>  *FAILED JUNIT TESTS* Name: junit.framework Failed: 1 test(s), Passed: 0
> test(s), Skipped: 0 test(s), Total: 1 test(s)
> *Failed: junit.framework.TestSuite.org.apache.lucene.search.TestScorerPerf
> * Name: org.apache.lucene.search Failed: 1 test(s), Passed: 702 test(s),
> Skipped: 5 test(s), Total: 708 test(s)
> *Failed: org.apache.lucene.search.TestScorerPerf.testConjunctions *
>  *CONSOLE OUTPUT* [...truncated 1847 lines...] [junit4] Tests with
> failures: [junit4] -
> org.apache.lucene.search.TestScorerPerf.testConjunctions [junit4] -
> org.apache.lucene.search.TestScorerPerf (suite) [junit4]  [junit4]
> [junit4] JVM J0: 1.18 .. 134.63 = 133.45s [junit4] JVM J1: 0.89 .. 128.01
> = 127.13s [junit4] JVM J2: 1.08 .. 129.33 = 128.24s [junit4] JVM J3: 1.13
> .. 7243.77 = 7242.65s [junit4] JVM J4: 0.88 .. 125.94 = 125.06s [junit4]
> JVM J5: 1.08 .. 127.14 = 126.06s [junit4] JVM J6: 1.33 .. 127.70 = 126.37s 
> [junit4]
> JVM J7: 1.08 .. 127.75 = 126.68s [junit4] Execution time total: 2 hours
> 43 seconds [junit4] Tests summary: 409 suites, 3241 tests, 1 suite-level
> error, 1 error, 113 ignored (50 assumptions) BUILD FAILED 
> /home/jenkins/workspace/lucene_trunk_linux_java8_64_test_only/checkout/lucene/build.xml:49:
> The following error occurred while executing this line: 
> /home/jenkins/workspace/lucene_trunk_linux_java8_64_test_only/checkout/lucene/common-build.xml:1348:
> The following error occurred while executing this line: 
> /home/jenkins/workspace/lucene_trunk_linux_java8_64_test_only/checkout/lucene/common-build.xml:956:
> There were test failures: 409 suites, 3241 tests, 1 suite-level error, 1
> error, 113 ignored (50 assumptions) Total time: 120 minutes 51 seconds Build
> step 'Invoke Ant' marked build as failure Archiving artifacts Recording
> test results Email was triggered for: Failure - 1st Trigger Failure - Any
> was overridden by another trigger and will not send an email. Trigger
> Failure - Still was overridden by another trigger and will not send an
> email. Sending email for trigger: Failure - 1st
>

Re: [CI] Lucene Linux Java 8 64 Test Only - Build # 28585 - Failure!

2015-02-03 Thread Dawid Weiss 
There is nothing special in the events file (just a stall before timeout).
It is suspicious where it reports the thread to be at the timeout time:

   [junit4]   2>  jstack at approximately timeout time 
   [junit4]   2>
"TEST-TestScorerPerf.testConjunctions-seed#[675750BB099F7976]" ID=176
RUNNABLE
   [junit4]   2>at 
org.apache.lucene.search.Query.toString(Query.java:70)
   [junit4]   2>at java.lang.String.valueOf(String.java:2981)



It seems impossible to me (it'd be inside the toString(String) of a
subclass?).

public String toString() {
70:return toString("");
}

Dawid

On Tue, Feb 3, 2015 at 10:30 AM, Michael McCandless 
wrote:

> TestScorerPerf.testConjunctions timed out @ 2 hours ... but this does not
> repro for me standalone, nor with master seed, using G1GC.  The test runs
> very quickly...
>
> Mike McCandless
>
> http://blog.mikemccandless.com
>
> On Tue, Feb 3, 2015 at 3:07 AM,  wrote:
>
>>   *BUILD FAILURE*
>> Build URL
>> http://build-eu-00.elasticsearch.org/job/lucene_trunk_linux_java8_64_test_only/28585/
>> Project:lucene_trunk_linux_java8_64_test_only Date of build:Tue, 03 Feb
>> 2015 07:06:18 +0100 Build duration:2 hr 0 min
>>  *CHANGES* No Changes
>>  *BUILD ARTIFACTS*
>> checkout/lucene/build/core/test/temp/junit4-J0-20150203_070621_306.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J1-20150203_070621_307.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J2-20150203_070621_307.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J3-20150203_070621_320.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J4-20150203_070621_318.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J5-20150203_070621_318.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J6-20150203_070621_320.events
>> 
>> checkout/lucene/build/core/test/temp/junit4-J7-20150203_070621_324.events
>> 
>>  *FAILED JUNIT TESTS* Name: junit.framework Failed: 1 test(s), Passed: 0
>> test(s), Skipped: 0 test(s), Total: 1 test(s)
>> *Failed:
>> junit.framework.TestSuite.org.apache.lucene.search.TestScorerPerf * Name:
>> org.apache.lucene.search Failed: 1 test(s), Passed: 702 test(s), Skipped: 5
>> test(s), Total: 708 test(s)
>> *Failed: org.apache.lucene.search.TestScorerPerf.testConjunctions *
>>  *CONSOLE OUTPUT* [...truncated 1847 lines...] [junit4] Tests with
>> failures: [junit4] -
>> org.apache.lucene.search.TestScorerPerf.testConjunctions [junit4] -
>> org.apache.lucene.search.TestScorerPerf (suite) [junit4]  [junit4]
>> [junit4] JVM J0: 1.18 .. 134.63 = 133.45s [junit4] JVM J1: 0.89 ..
>> 128.01 = 127.13s [junit4] JVM J2: 1.08 .. 129.33 = 128.24s [junit4] JVM
>> J3: 1.13 .. 7243.77 = 7242.65s [junit4] JVM J4: 0.88 .. 125.94 = 125.06s 
>> [junit4]
>> JVM J5: 1.08 .. 127.14 = 126.06s [junit4] JVM J6: 1.33 .. 127.70 =
>> 126.37s [junit4] JVM J7: 1.08 .. 127.75 = 126.68s [junit4] Execution
>> time total: 2 hours 43 seconds [junit4] Tests summary: 409 suites, 3241
>> tests, 1 suite-level error, 1 error, 113 ignored (50 assumptions) BUILD
>> FAILED 
>> /home/jenkins/workspace/lucene_trunk_linux_java8_64_test_only/checkout/lucene/build.xml:49:
>> The following error occurred while executing this line: 
>> /home/jenkins/workspace/lucene_trunk_linux_java8_64_test_only/checkout/lucene/common-build.xml:1348:
>> The following error occurred while executing this line: 
>> /home/jenkins/workspace/lucene_trunk_linux_java8_64_test_only/checkout/lucene/common-build.xml:956:
>> There were test failures: 409 suites, 3241 tests, 1 suite-level error, 1
>> error, 113 ignored (50 assumptions) Total time: 120 minutes 51 seconds Build
>> step 'I