Is this 100ms timeout arbitrary? this failed on my slow windows computer, nobody knows what it could have also be doing (DEFRAG or something)... can we improve the test?
On Thu, Oct 4, 2012 at 2:26 AM, <[email protected]> wrote: > See <http://sierranevada.servebeer.com:8080/job/slow-io-beasting/392/> > > ------------------------------------------ > [...truncated 955 lines...] > [junit4:junit4] Completed on J1 in 0.05s, 7 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.spans.TestNearSpansOrdered > [junit4:junit4] Completed on J2 in 0.06s, 10 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestDocCount > [junit4:junit4] Completed on J3 in 0.09s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestSumDocFreq > [junit4:junit4] Completed on J1 in 0.05s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestUniqueTermCount > [junit4:junit4] Completed on J2 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.TestSearchForDuplicates > [junit4:junit4] Completed on J3 in 0.11s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestPerSegmentDeletes > [junit4:junit4] Completed on J1 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestSmallFloat > [junit4:junit4] Completed on J2 in 0.09s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestParallelReaderEmptyIndex > [junit4:junit4] Completed on J3 in 0.05s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestTopDocsCollector > [junit4:junit4] Completed on J1 in 0.05s, 8 tests > [junit4:junit4] > [junit4:junit4] Suite: > org.apache.lucene.util.junitcompat.TestBeforeAfterOverrides > [junit4:junit4] Completed on J2 in 0.03s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestCachingWrapperFilter > [junit4:junit4] Completed on J3 in 0.03s, 5 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestDocIdSet > [junit4:junit4] Completed on J1 in 0.02s, 3 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestTermScorer > [junit4:junit4] Completed on J2 in 0.14s, 3 tests > [junit4:junit4] > [junit4:junit4] Suite: > org.apache.lucene.util.junitcompat.TestSameRandomnessLocalePassedOrNot > [junit4:junit4] Completed on J3 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestFieldValueFilter > [junit4:junit4] Completed on J1 in 0.05s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestPhrasePrefixQuery > [junit4:junit4] Completed on J2 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestPositionIncrement > [junit4:junit4] Completed on J3 in 0.03s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestCharsRef > [junit4:junit4] Completed on J1 in 0.03s, 8 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.junitcompat.TestCodecReported > [junit4:junit4] Completed on J2 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestParallelTermEnum > [junit4:junit4] Completed on J3 in 0.08s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestSameTokenSamePosition > [junit4:junit4] Completed on J1 in 0.03s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestFieldCacheTermsFilter > [junit4:junit4] Completed on J2 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestNot > [junit4:junit4] Completed on J3 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestAttributeSource > [junit4:junit4] Completed on J1 in 0.03s, 5 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestDateSort > [junit4:junit4] Completed on J2 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.document.TestBinaryDocument > [junit4:junit4] Completed on J3 in 0.08s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.store.TestLock > [junit4:junit4] Completed on J1 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestBytesRef > [junit4:junit4] Completed on J2 in 0.02s, 5 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestPriorityQueue > [junit4:junit4] Completed on J3 in 0.03s, 4 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestDocBoost > [junit4:junit4] Completed on J1 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestNamedSPILoader > [junit4:junit4] Completed on J2 in 0.02s, 3 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestFieldInfos > [junit4:junit4] Completed on J3 in 0.02s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestIsCurrent > [junit4:junit4] Completed on J1 in 0.08s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestPrefixFilter > [junit4:junit4] Completed on J2 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestScoreCachingWrappingScorer > [junit4:junit4] Completed on J3 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestBitUtil > [junit4:junit4] Completed on J1 in 0.02s, 2 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.analysis.TestNumericTokenStream > [junit4:junit4] Completed on J2 in 0.03s, 4 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestIndexWriterLockRelease > [junit4:junit4] Completed on J3 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestCloseableThreadLocal > [junit4:junit4] Completed on J1 in 0.02s, 3 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestByteBlockPool > [junit4:junit4] Completed on J2 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: > org.apache.lucene.analysis.tokenattributes.TestSimpleAttributeImpl > [junit4:junit4] Completed on J3 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestTerm > [junit4:junit4] Completed on J1 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.TestWorstCaseTestBehavior > [junit4:junit4] Completed on J2 in 0.02s, 6 tests, 6 skipped > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.analysis.TestCharFilter > [junit4:junit4] Completed on J3 in 0.02s, 4 tests > [junit4:junit4] > [junit4:junit4] Suite: > org.apache.lucene.codecs.lucene3x.TestTermInfosReaderIndex > [junit4:junit4] Completed on J1 in 0.22s, 3 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.document.TestField > [junit4:junit4] Completed on J3 in 0.06s, 24 tests > [junit4:junit4] > [junit4:junit4] Suite: > org.apache.lucene.codecs.lucene40.TestLucene40PostingsFormat > [junit4:junit4] Completed on J2 in 0.76s, 7 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestBagOfPostings > [junit4:junit4] Completed on J3 in 0.44s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestPayloadsOnVectors > [junit4:junit4] Completed on J2 in 0.03s, 3 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.search.TestConjunctions > [junit4:junit4] Completed on J2 in 0.03s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestFilterIterator > [junit4:junit4] Completed on J2 in 0.05s, 8 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestIndexableBinaryStringTools > [junit4:junit4] Completed on J2 in 0.23s, 5 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestPostingsFormat > [junit4:junit4] Completed on J3 in 1.19s, 7 tests > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.util.TestMaxFailuresRule > [junit4:junit4] Completed on J2 in 0.47s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: > org.apache.lucene.util.junitcompat.TestFailIfUnreferencedFiles > [junit4:junit4] Completed on J3 in 0.02s, 1 test > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestDocumentsWriterStallControl > [junit4:junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=TestDocumentsWriterStallControl -Dtests.method=testSimpleStall > -Dtests.seed=D8BB387CFB17543 -Dtests.slow=true -Dtests.locale=es_PE > -Dtests.timezone=Atlantic/Canary -Dtests.file.encoding=UTF-8 > [junit4:junit4] FAILURE 0.14s J0 | > TestDocumentsWriterStallControl.testSimpleStall <<< > [junit4:junit4] > Throwable #1: java.lang.AssertionError: timed out > waiting for state: WAITING timeout: 100 ms > [junit4:junit4] > at > __randomizedtesting.SeedInfo.seed([D8BB387CFB17543:5E1CA51F4EBFA074]:0) > [junit4:junit4] > at org.junit.Assert.fail(Assert.java:93) > [junit4:junit4] > at > org.apache.lucene.index.TestDocumentsWriterStallControl.awaitState(TestDocumentsWriterStallControl.java:348) > [junit4:junit4] > at > org.apache.lucene.index.TestDocumentsWriterStallControl.testSimpleStall(TestDocumentsWriterStallControl.java:49) > [junit4:junit4] > at > sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > [junit4:junit4] > at > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) > [junit4:junit4] > at > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) > [junit4:junit4] > at java.lang.reflect.Method.invoke(Method.java:597) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51) > [junit4:junit4] > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693) > [junit4:junit4] > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70) > [junit4:junit4] > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > [junit4:junit4] > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358) > [junit4:junit4] > at java.lang.Thread.run(Thread.java:662) > [junit4:junit4] 2> NOTE: test params are: codec=Lucene40: {}, > sim=DefaultSimilarity, locale=es_PE, timezone=Atlantic/Canary > [junit4:junit4] 2> NOTE: Windows Vista 6.0 x86/Sun Microsystems Inc. > 1.6.0_23 (32-bit)/cpus=4,threads=1,free=413851456,total=518979584 > [junit4:junit4] 2> NOTE: All tests run in this JVM: [TestDocValuesScoring, > TestIndexWriterCommit, TestFlushByRamOrCountsPolicy, > TestSpanMultiTermQueryWrapper, TestWeakIdentityMap, > TestSpanSearchEquivalence, TestBooleanMinShouldMatch, TestDocTermOrds, > TestVirtualMethod, TestOpenBitSet, TestOmitNorms, ThrowInUncaught, > TestIndexWriterExceptions, TestNGramPhraseQuery, TestReuseDocsEnum, > TestHugeRamFile, TestTwoPhaseCommitTool, TestCodecs, > TestParallelAtomicReader, TestTermVectors, TestPhraseQuery, > TestNewestSegment, TestSearch, TestRecyclingByteBlockAllocator, > TestIndexWriter, TestSimpleSearchEquivalence, TestByteArrayDataInput, > TestRollback, TestMatchAllDocsQuery, TestThreadedForceMerge, > TestIndexWriterConfig, TestImpersonation, TestIndexCommit, > TestFilterAtomicReader, InBeforeClass, InAfterClass, InTestMethod, > NonStringProperties, TestForTooMuchCloning, TestExternalCodecs, > TestAutomatonQueryUnicode, TestOmitPositions, TestPackedInts, > TestShardSearching, TestNumericRangeQuery32, TestBasics, TestAddIndexes, > TestIndexWriterMerging, TestIndexWriterForceMerge, TestSimpleExplanations, > TestCustomNorms, TestDocumentsWriterStallControl] > [junit4:junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=TestDocumentsWriterStallControl -Dtests.seed=D8BB387CFB17543 > -Dtests.slow=true -Dtests.locale=es_PE -Dtests.timezone=Atlantic/Canary > -Dtests.file.encoding=UTF-8 > [junit4:junit4] ERROR 0.00s J0 | TestDocumentsWriterStallControl (suite) <<< > [junit4:junit4] > Throwable #1: > com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from > SUITE scope at org.apache.lucene.index.TestDocumentsWriterStallControl: > [junit4:junit4] > 1) Thread[id=473, name=Thread-351, state=WAITING, > group=TGRP-TestDocumentsWriterStallControl] > [junit4:junit4] > at java.lang.Object.wait(Native Method) > [junit4:junit4] > at java.lang.Object.wait(Object.java:485) > [junit4:junit4] > at > org.apache.lucene.index.DocumentsWriterStallControl.waitIfStalled(DocumentsWriterStallControl.java:74) > [junit4:junit4] > at > org.apache.lucene.index.TestDocumentsWriterStallControl$2.run(TestDocumentsWriterStallControl.java:322) > [junit4:junit4] > at > __randomizedtesting.SeedInfo.seed([D8BB387CFB17543]:0) > [junit4:junit4] Completed on J0 in 23.23s, 3 tests, 1 failure, 1 error <<< > FAILURES! > [junit4:junit4] > [junit4:junit4] Suite: org.apache.lucene.index.TestBackwardsCompatibility3x > [junit4:junit4] Completed on J1 in 6.32s, 16 tests > [junit4:junit4] > [junit4:junit4] > [junit4:junit4] Tests with failures: > [junit4:junit4] - > org.apache.lucene.index.TestDocumentsWriterStallControl.testSimpleStall > [junit4:junit4] - org.apache.lucene.index.TestDocumentsWriterStallControl > (suite) > [junit4:junit4] > [junit4:junit4] > [junit4:junit4] JVM J0: 1.01 .. 95.79 = 94.78s > [junit4:junit4] JVM J1: 0.76 .. 99.41 = 98.65s > [junit4:junit4] JVM J2: 0.76 .. 95.30 = 94.53s > [junit4:junit4] JVM J3: 1.01 .. 95.36 = 94.34s > [junit4:junit4] Execution time total: 1 minute 39 seconds > [junit4:junit4] Tests summary: 315 suites, 1834 tests, 1 suite-level error, 1 > failure, 22 ignored (14 assumptions) > > BUILD FAILED > <http://sierranevada.servebeer.com:8080/job/slow-io-beasting/ws/build.xml>:49: > The following error occurred while executing this line: > <http://sierranevada.servebeer.com:8080/job/slow-io-beasting/ws/common-build.xml>:1143: > The following error occurred while executing this line: > <http://sierranevada.servebeer.com:8080/job/slow-io-beasting/ws/common-build.xml>:816: > There were test failures: 315 suites, 1834 tests, 1 suite-level error, 1 > failure, 22 ignored (14 assumptions) > > Total time: 1 minute 49 seconds > Build step 'Invoke Ant' marked build as failure > > --------------------------------------------------------------------- > To unsubscribe, e-mail: [email protected] > For additional commands, e-mail: [email protected] > --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
