I think it's a bug in the test, plus, the build machine was running so slow that the first doc collected hit the timeout on these threads.
It's asserting that the doc that hit the timeout is > 0, and from the test it seems like it intends for the query to never match doc 0, but, the query does in fact match doc 0. I think the fix is easy -- just change this: String qtxt = "one"; for (int i = 0; i < docText.length; i++) { qtxt += ' ' + docText[i]; // large query so that search will be longer } to this: String qtxt = "one"; for (int i = 1; i < docText.length; i++) { qtxt += ' ' + docText[i]; // large query so that search will be longer } (ie start i from 1 not 0, so that the 0th doc will never match). I'll commit. Mike On Mon, Nov 9, 2009 at 4:24 AM, Uwe Schindler <u...@thetaphi.de> wrote: > No idea, all tests pass here on two machines (Win, Solaris). > > ----- > Uwe Schindler > H.-H.-Meier-Allee 63, D-28213 Bremen > http://www.thetaphi.de > eMail: u...@thetaphi.de > >> -----Original Message----- >> From: Apache Hudson Server [mailto:hud...@hudson.zones.apache.org] >> Sent: Monday, November 09, 2009 5:13 AM >> To: java-dev@lucene.apache.org >> Subject: Build failed in Hudson: Lucene-trunk #1003 >> >> See <http://hudson.zones.apache.org/hudson/job/Lucene-trunk/1003/changes> >> >> Changes: >> >> [uschindler] LUCENE-2030: Fix locks in CachingWrapperFilter and >> CachingSpanFilter (make members private, also synchronize on WeakHashMap >> build, use new Java5 ReentrantLock) >> >> [mikemccand] LUCENE-2045: fix false FNFE >> >> [rmuir] LUCENE-2012: add remaining @overrides (contrib,demo) >> >> [mikemccand] LUCENE-2044: add delete.percent.rand.seed to control random >> seed for DeleteByPercentTask >> >> ------------------------------------------ >> [...truncated 20714 lines...] >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.doTestTimeout(TestTimeL >> imitingCollector.java:183) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.access$100(TestTimeLimi >> tingCollector.java:41) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector$1.run(TestTimeLimitingC >> ollector.java:277) >> [junit] Exception in thread "Thread-59" >> junit.framework.AssertionFailedError: doc collected at timeout must be > >> 0! >> [junit] at junit.framework.Assert.fail(Assert.java:47) >> [junit] at junit.framework.Assert.assertTrue(Assert.java:20) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.doTestTimeout(TestTimeL >> imitingCollector.java:183) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.access$100(TestTimeLimi >> tingCollector.java:41) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector$1.run(TestTimeLimitingC >> ollector.java:277) >> [junit] Exception in thread "Thread-85" >> junit.framework.AssertionFailedError: doc collected at timeout must be > >> 0! >> [junit] at junit.framework.Assert.fail(Assert.java:47) >> [junit] at junit.framework.Assert.assertTrue(Assert.java:20) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.doTestTimeout(TestTimeL >> imitingCollector.java:183) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.access$100(TestTimeLimi >> tingCollector.java:41) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector$1.run(TestTimeLimitingC >> ollector.java:277) >> [junit] Exception in thread "Thread-81" >> junit.framework.AssertionFailedError: doc collected at timeout must be > >> 0! >> [junit] at junit.framework.Assert.fail(Assert.java:47) >> [junit] at junit.framework.Assert.assertTrue(Assert.java:20) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.doTestTimeout(TestTimeL >> imitingCollector.java:183) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.access$100(TestTimeLimi >> tingCollector.java:41) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector$1.run(TestTimeLimitingC >> ollector.java:277) >> [junit] Exception in thread "Thread-54" >> junit.framework.AssertionFailedError: doc collected at timeout must be > >> 0! >> [junit] at junit.framework.Assert.fail(Assert.java:47) >> [junit] at junit.framework.Assert.assertTrue(Assert.java:20) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.doTestTimeout(TestTimeL >> imitingCollector.java:183) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.access$100(TestTimeLimi >> tingCollector.java:41) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector$1.run(TestTimeLimitingC >> ollector.java:277) >> [junit] ------------- ---------------- --------------- >> [junit] Testcase: >> testTimeoutMultiThreaded(org.apache.lucene.search.TestTimeLimitingCollecto >> r): FAILED >> [junit] some threads failed! expected:<50> but was:<45> >> [junit] junit.framework.AssertionFailedError: some threads failed! >> expected:<50> but was:<45> >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.doTestMultiThreads(Test >> TimeLimitingCollector.java:293) >> [junit] at >> org.apache.lucene.search.TestTimeLimitingCollector.testTimeoutMultiThreade >> d(TestTimeLimitingCollector.java:265) >> [junit] at >> org.apache.lucene.util.LuceneTestCase.runBare(LuceneTestCase.java:208) >> [junit] >> [junit] >> [junit] Test org.apache.lucene.search.TestTimeLimitingCollector FAILED >> [junit] Testsuite: org.apache.lucene.search.TestTopDocsCollector >> [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 1.464 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.TestTopScoreDocCollector >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.307 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.TestWildcard >> [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 1.424 sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.search.function.TestCustomScoreQuery >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 245.692 >> sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.function.TestDocValues >> [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.607 sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.search.function.TestFieldScoreQuery >> [junit] Tests run: 12, Failures: 0, Errors: 0, Time elapsed: 4.691 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.function.TestOrdValues >> [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 2.678 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.function.TestValueSource >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.647 sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.search.payloads.TestPayloadNearQuery >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 7.167 sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.search.payloads.TestPayloadTermQuery >> [junit] Tests run: 6, Failures: 0, Errors: 0, Time elapsed: 14.21 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.spans.TestBasics >> [junit] Tests run: 20, Failures: 0, Errors: 0, Time elapsed: 71.959 >> sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.search.spans.TestFieldMaskingSpanQuery >> [junit] Tests run: 11, Failures: 0, Errors: 0, Time elapsed: 9.233 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.spans.TestNearSpansOrdered >> [junit] Tests run: 10, Failures: 0, Errors: 0, Time elapsed: 4.425 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.spans.TestPayloadSpans >> [junit] Tests run: 10, Failures: 0, Errors: 0, Time elapsed: 19.776 >> sec >> [junit] >> [junit] ------------- Standard Output --------------- >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:2 >> [junit] doc:0 s:3 e:6 one:Entity:3 >> [junit] doc:0 s:3 e:6 three:Noise:5 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:3 >> [junit] doc:0 s:0 e:3 xx:Entity:0 >> [junit] doc:0 s:0 e:3 yy:Noise:2 >> [junit] doc:0 s:0 e:3 rr:Noise:1 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:3 >> [junit] doc:1 s:0 e:4 rr:Noise:3 >> [junit] doc:1 s:0 e:4 yy:Noise:1 >> [junit] doc:1 s:0 e:4 xx:Entity:0 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:3 >> [junit] doc:0 s:0 e:3 xx:Entity:0 >> [junit] doc:0 s:0 e:3 yy:Noise:2 >> [junit] doc:0 s:0 e:3 rr:Noise:1 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:3 >> [junit] doc:0 s:0 e:3 yy:Noise:2 >> [junit] doc:0 s:0 e:3 rr:Noise:1 >> [junit] doc:0 s:0 e:3 xx:Entity:0 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:3 >> [junit] doc:1 s:0 e:4 xx:Entity:0 >> [junit] doc:1 s:0 e:4 yy:Noise:1 >> [junit] doc:1 s:0 e:4 rr:Noise:3 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:3 >> [junit] doc:2 s:0 e:5 ss:Noise:2 >> [junit] doc:2 s:0 e:5 pp:Noise:3 >> [junit] doc:2 s:0 e:5 qq:Noise:1 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:8 >> [junit] doc:3 s:0 e:11 six:Noise:5 >> [junit] doc:3 s:0 e:11 two:Noise:1 >> [junit] doc:3 s:0 e:11 nine:Noise:8 >> [junit] doc:3 s:0 e:11 ten:Noise:9 >> [junit] doc:3 s:0 e:11 eleven:Noise:10 >> [junit] doc:3 s:0 e:11 three:Noise:2 >> [junit] doc:3 s:0 e:11 one:Entity:0 >> [junit] doc:3 s:0 e:11 five:Noise:4 >> [junit] >> [junit] Spans Dump -- >> [junit] payloads for span:8 >> [junit] doc:4 s:0 e:11 ten:Noise:10 >> [junit] doc:4 s:0 e:11 one:Entity:1 >> [junit] doc:4 s:0 e:11 five:Noise:5 >> [junit] doc:4 s:0 e:11 two:Noise:2 >> [junit] doc:4 s:0 e:11 six:Noise:6 >> [junit] doc:4 s:0 e:11 nine:Noise:0 >> [junit] doc:4 s:0 e:11 eleven:Noise:9 >> [junit] doc:4 s:0 e:11 three:Noise:3 >> [junit] match:a:Noise:10 >> [junit] match:k:Noise:11 >> [junit] Num payloads:1 >> [junit] rr:Noise:1 >> [junit] ------------- ---------------- --------------- >> [junit] Testsuite: org.apache.lucene.search.spans.TestSpanExplanations >> [junit] Tests run: 31, Failures: 0, Errors: 0, Time elapsed: 21.364 >> sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.search.spans.TestSpanExplanationsOfNonMatches >> [junit] Tests run: 31, Failures: 0, Errors: 0, Time elapsed: 2.208 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.spans.TestSpans >> [junit] Tests run: 25, Failures: 0, Errors: 0, Time elapsed: 25.908 >> sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.spans.TestSpansAdvanced >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 2.797 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.search.spans.TestSpansAdvanced2 >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 9.969 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestBufferedIndexInput >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 58.672 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestDirectory >> [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 0.928 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestFileSwitchDirectory >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 1.485 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestHugeRamFile >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 6.667 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestLock >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.428 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestLockFactory >> [junit] Tests run: 9, Failures: 0, Errors: 0, Time elapsed: 12.675 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestRAMDirectory >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 8.29 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.store.TestWindowsMMap >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 3.803 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestAttributeSource >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 0.686 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestBitVector >> [junit] Tests run: 7, Failures: 0, Errors: 0, Time elapsed: 9.567 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestCloseableThreadLocal >> [junit] Tests run: 3, Failures: 0, Errors: 0, Time elapsed: 0.575 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestFieldCacheSanityChecker >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 8.995 sec >> [junit] >> [junit] Testsuite: >> org.apache.lucene.util.TestIndexableBinaryStringTools >> [junit] Tests run: 5, Failures: 0, Errors: 0, Time elapsed: 10.08 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestNumericUtils >> [junit] Tests run: 8, Failures: 0, Errors: 0, Time elapsed: 0.973 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestOpenBitSet >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 6.86 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestPriorityQueue >> [junit] Tests run: 4, Failures: 0, Errors: 0, Time elapsed: 1.262 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestRamUsageEstimator >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.492 sec >> [junit] >> [junit] ------------- Standard Output --------------- >> [junit] size:56 >> [junit] size:60 >> [junit] size:48 >> [junit] size:202 >> [junit] ------------- ---------------- --------------- >> [junit] Testsuite: org.apache.lucene.util.TestSmallFloat >> [junit] Tests run: 2, Failures: 0, Errors: 0, Time elapsed: 0.56 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestSortedVIntList >> [junit] Tests run: 18, Failures: 0, Errors: 0, Time elapsed: 1.219 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestStringHelper >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.409 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestStringIntern >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 72.208 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.TestVersion >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.541 sec >> [junit] >> [junit] Testsuite: org.apache.lucene.util.cache.TestSimpleLRUCache >> [junit] Tests run: 1, Failures: 0, Errors: 0, Time elapsed: 0.4 sec >> [junit] >> >> BUILD FAILED >> <http://hudson.zones.apache.org/hudson/job/Lucene-trunk/ws/trunk/common- >> build.xml>:442: The following error occurred while executing this line: >> <http://hudson.zones.apache.org/hudson/job/Lucene-trunk/ws/trunk/common- >> build.xml>:435: Tests failed! >> >> Total time: 37 minutes 11 seconds >> Publishing Javadoc >> Archiving artifacts >> Recording test results >> Publishing Clover coverage report... >> No Clover report will be published due to a Build Failure >> >> >> --------------------------------------------------------------------- >> To unsubscribe, e-mail: java-dev-unsubscr...@lucene.apache.org >> For additional commands, e-mail: java-dev-h...@lucene.apache.org > > > > --------------------------------------------------------------------- > To unsubscribe, e-mail: java-dev-unsubscr...@lucene.apache.org > For additional commands, e-mail: java-dev-h...@lucene.apache.org > > --------------------------------------------------------------------- To unsubscribe, e-mail: java-dev-unsubscr...@lucene.apache.org For additional commands, e-mail: java-dev-h...@lucene.apache.org