Hi, I think this happens when the VBOX virtual machine daemon adjusts time at wrong moment or some other VM running on same hardware causes some hang, maybe caused by saturated IO. How long is the time limit, the nanosecond unit makes it hard to figure out from error message.
Uwe Am 7. Dezember 2015 20:02:26 MEZ, schrieb Policeman Jenkins Server <[email protected]>: >Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Solaris/238/ >Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC > >1 tests failed. >FAILED: >org.apache.lucene.index.TestExitableDirectoryReader.testExitableFilterIndexReader > >Error Message: >The request took too long to iterate over terms. Timeout: timeoutAt: >597367202266360 (System.nanoTime(): 597371702796891), >TermsEnum=org.apache.lucene.codecs.blockterms.BlockTermsReader$FieldReader$SegmentTermsEnum@3a15c305 > >Stack Trace: >org.apache.lucene.index.ExitableDirectoryReader$ExitingReaderException: >The request took too long to iterate over terms. Timeout: timeoutAt: >597367202266360 (System.nanoTime(): 597371702796891), >TermsEnum=org.apache.lucene.codecs.blockterms.BlockTermsReader$FieldReader$SegmentTermsEnum@3a15c305 > at >__randomizedtesting.SeedInfo.seed([C503106CB6F6D521:7D66BD2DDD2C5CD8]:0) > at >org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.checkAndThrow(ExitableDirectoryReader.java:167) > at >org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.<init>(ExitableDirectoryReader.java:157) > at >org.apache.lucene.index.ExitableDirectoryReader$ExitableTerms.iterator(ExitableDirectoryReader.java:141) > at >org.apache.lucene.index.FilterLeafReader$FilterTerms.iterator(FilterLeafReader.java:113) > at >org.apache.lucene.index.TestExitableDirectoryReader$TestReader$TestTerms.iterator(TestExitableDirectoryReader.java:58) > at org.apache.lucene.index.Terms.intersect(Terms.java:72) > at >org.apache.lucene.util.automaton.CompiledAutomaton.getTermsEnum(CompiledAutomaton.java:336) > at >org.apache.lucene.search.AutomatonQuery.getTermsEnum(AutomatonQuery.java:108) > at >org.apache.lucene.search.MultiTermQuery.getTermsEnum(MultiTermQuery.java:318) > at >org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.rewrite(MultiTermQueryConstantScoreWrapper.java:146) > at >org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.bulkScorer(MultiTermQueryConstantScoreWrapper.java:199) > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:818) > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:535) > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:744) > at >org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:460) > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:489) > at >org.apache.lucene.index.TestExitableDirectoryReader.testExitableFilterIndexReader(TestExitableDirectoryReader.java:127) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at >sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > at >sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > at java.lang.reflect.Method.invoke(Method.java:497) > at >com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) > at >org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) > at >org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) > at >org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) > at >org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) > at >org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at >com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at >com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) > at >com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) > at >com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) > at >com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) > at >org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at >org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) > at >com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at >com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at >org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) > at >org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > at >org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) > at >org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) > at >com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at >com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > > >Build Log: >[...truncated 697 lines...] > [junit4] Suite: org.apache.lucene.index.TestExitableDirectoryReader >[junit4] 2> NOTE: reproduce with: ant test >-Dtestcase=TestExitableDirectoryReader >-Dtests.method=testExitableFilterIndexReader >-Dtests.seed=C503106CB6F6D521 -Dtests.slow=true -Dtests.locale=ar_SD >-Dtests.timezone=America/Martinique -Dtests.asserts=true >-Dtests.file.encoding=UTF-8 >[junit4] ERROR 5.68s J1 | >TestExitableDirectoryReader.testExitableFilterIndexReader <<< >[junit4] > Throwable #1: >org.apache.lucene.index.ExitableDirectoryReader$ExitingReaderException: >The request took too long to iterate over terms. Timeout: timeoutAt: >597367202266360 (System.nanoTime(): 597371702796891), >TermsEnum=org.apache.lucene.codecs.blockterms.BlockTermsReader$FieldReader$SegmentTermsEnum@3a15c305 >[junit4] > at >__randomizedtesting.SeedInfo.seed([C503106CB6F6D521:7D66BD2DDD2C5CD8]:0) >[junit4] > at >org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.checkAndThrow(ExitableDirectoryReader.java:167) >[junit4] > at >org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.<init>(ExitableDirectoryReader.java:157) >[junit4] > at >org.apache.lucene.index.ExitableDirectoryReader$ExitableTerms.iterator(ExitableDirectoryReader.java:141) >[junit4] > at >org.apache.lucene.index.FilterLeafReader$FilterTerms.iterator(FilterLeafReader.java:113) >[junit4] > at >org.apache.lucene.index.TestExitableDirectoryReader$TestReader$TestTerms.iterator(TestExitableDirectoryReader.java:58) >[junit4] > at >org.apache.lucene.index.Terms.intersect(Terms.java:72) >[junit4] > at >org.apache.lucene.util.automaton.CompiledAutomaton.getTermsEnum(CompiledAutomaton.java:336) >[junit4] > at >org.apache.lucene.search.AutomatonQuery.getTermsEnum(AutomatonQuery.java:108) >[junit4] > at >org.apache.lucene.search.MultiTermQuery.getTermsEnum(MultiTermQuery.java:318) >[junit4] > at >org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.rewrite(MultiTermQueryConstantScoreWrapper.java:146) >[junit4] > at >org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.bulkScorer(MultiTermQueryConstantScoreWrapper.java:199) >[junit4] > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:818) >[junit4] > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:535) >[junit4] > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:744) >[junit4] > at >org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:460) >[junit4] > at >org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:489) >[junit4] > at >org.apache.lucene.index.TestExitableDirectoryReader.testExitableFilterIndexReader(TestExitableDirectoryReader.java:127) > [junit4] > at java.lang.Thread.run(Thread.java:745) >[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): >{default=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, >docValues:{}, >sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {default=IB >LL-D1}, locale=ar_SD, timezone=America/Martinique >[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_66 >(64-bit)/cpus=3,threads=1,free=481193944,total=501219328 >[junit4] 2> NOTE: All tests run in this JVM: [TestSpanOrQuery, >TestIndexWriterDeleteByQuery, Test2BSortedDocValuesFixedSorted, >MultiCollectorTest, TestLucene50TermVectorsFormat, TestSpansAdvanced2, >TestPerFieldDocValuesFormat, TestStressAdvance, >TestExitableDirectoryReader] >[junit4] Completed [57/414 (1!)] on J1 in 5.83s, 1 test, 1 error <<< >FAILURES! > >[...truncated 1158 lines...] >BUILD FAILED >/export/home/jenkins/workspace/Lucene-Solr-5.x-Solaris/build.xml:792: >The following error occurred while executing this line: >/export/home/jenkins/workspace/Lucene-Solr-5.x-Solaris/build.xml:736: >The following error occurred while executing this line: >/export/home/jenkins/workspace/Lucene-Solr-5.x-Solaris/build.xml:59: >The following error occurred while executing this line: >/export/home/jenkins/workspace/Lucene-Solr-5.x-Solaris/lucene/build.xml:50: >The following error occurred while executing this line: >/export/home/jenkins/workspace/Lucene-Solr-5.x-Solaris/lucene/common-build.xml:1456: >The following error occurred while executing this line: >/export/home/jenkins/workspace/Lucene-Solr-5.x-Solaris/lucene/common-build.xml:1010: >There were test failures: 414 suites (1 ignored), 3344 tests, 1 error, >49 ignored (45 assumptions) [seed: C503106CB6F6D521] > >Total time: 7 minutes 23 seconds >Build step 'Invoke Ant' marked build as failure >Archiving artifacts >[WARNINGS] Skipping publisher since build result is FAILURE >Recording test results >Email was triggered for: Failure - Any >Sending email for trigger: Failure - Any -- Uwe Schindler H.-H.-Meier-Allee 63, 28213 Bremen http://www.thetaphi.de -- Uwe Schindler H.-H.-Meier-Allee 63, 28213 Bremen http://www.thetaphi.de -- Uwe Schindler H.-H.-Meier-Allee 63, 28213 Bremen http://www.thetaphi.de
