Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1935/

3 tests failed.
FAILED:  
org.apache.lucene.index.TestBinaryDocValuesUpdates.testManyReopensAndFields

Error Message:
invalid value for doc=0, field=f1, reader=_6(7.0.0):c63:fieldInfosGen=2:dvGen=2 
expected:<4> but was:<3>

Stack Trace:
java.lang.AssertionError: invalid value for doc=0, field=f1, 
reader=_6(7.0.0):c63:fieldInfosGen=2:dvGen=2 expected:<4> but was:<3>
        at 
__randomizedtesting.SeedInfo.seed([4F2C0D8C11091CBF:79D06FA390FC7FA3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.lucene.index.TestBinaryDocValuesUpdates.testManyReopensAndFields(TestBinaryDocValuesUpdates.java:844)
        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:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        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:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.lucene.index.TestMixedDocValuesUpdates.testManyReopensAndFields

Error Message:
invalid binary value for doc=0, field=f1, 
reader=_c(7.0.0):C114:fieldInfosGen=1:dvGen=1 expected:<5> but was:<4>

Stack Trace:
java.lang.AssertionError: invalid binary value for doc=0, field=f1, 
reader=_c(7.0.0):C114:fieldInfosGen=1:dvGen=1 expected:<5> but was:<4>
        at 
__randomizedtesting.SeedInfo.seed([4F2C0D8C11091CBF:79D06FA390FC7FA3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.lucene.index.TestMixedDocValuesUpdates.testManyReopensAndFields(TestMixedDocValuesUpdates.java:141)
        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:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        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:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.update.AutoCommitTest.testMaxDocs

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([28EFB5E8921CC2C:BB0F2D81A5CBC8A6]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:878)
        at 
org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:225)
        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:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        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:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound=1]
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
</response>

        request was:q=id:14&qt=&start=0&rows=20&version=2.2
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:871)
        ... 40 more




Build Log:
[...truncated 1466 lines...]
   [junit4] Suite: org.apache.lucene.index.TestBinaryDocValuesUpdates
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestBinaryDocValuesUpdates -Dtests.method=testManyReopensAndFields 
-Dtests.seed=4F2C0D8C11091CBF -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=it-CH -Dtests.timezone=America/Halifax -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.09s J2 | 
TestBinaryDocValuesUpdates.testManyReopensAndFields <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: invalid value for 
doc=0, field=f1, reader=_6(7.0.0):c63:fieldInfosGen=2:dvGen=2 expected:<4> but 
was:<3>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4F2C0D8C11091CBF:79D06FA390FC7FA3]:0)
   [junit4]    >        at 
org.apache.lucene.index.TestBinaryDocValuesUpdates.testManyReopensAndFields(TestBinaryDocValuesUpdates.java:844)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] IGNOR/A 0.00s J2 | TestBinaryDocValuesUpdates.testTonsOfUpdates
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4]   1> TEST: isNRT=false reader1=StandardDirectoryReader(segments_1:4 
_0(7.0.0):C2)
   [junit4]   1> TEST: now reopen
   [junit4]   2> NOTE: test params are: 
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
 chunkSize=29080, maxDocsPerChunk=929, blockSize=849), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
 chunkSize=29080, blockSize=849)), sim=RandomSimilarity(queryNorm=true): {}, 
locale=it-CH, timezone=America/Halifax
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=200136928,total=296222720
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestComplexExplanationsOfNonMatches, TestIOUtils, TestDemoParallelLeafReader, 
TestIndexCommit, TestMultiset, TestRollingBuffer, TestMutablePointsReaderUtils, 
TestRamUsageEstimator, TestSegmentTermDocs, TestIndexSearcher, 
TestAttributeSource, TestTopDocsCollector, Test2BBKDPoints, 
TestFastCompressionMode, TestNumericUtils, TestSpanSearchEquivalence, 
TestTopDocsMerge, TestStopFilter, TestLucene70NormsFormat, TestAutomaton, 
TestRegexpQuery, TestSortedSetDocValues, TestIndexWriterReader, 
TestBufferedIndexInput, TestIntsRef, TestGeoEncodingUtils, 
TestIndexWriterLockRelease, TestScorerPerf, TestLSBRadixSorter, 
TestUsageTrackingFilterCachingPolicy, TestIndexWriterCommit, 
TestLongRangeFieldQueries, TestSimpleExplanationsOfNonMatches, 
TestLucene50StoredFieldsFormatHighCompression, TestSentinelIntSet, 
TestSegmentTermEnum, Test2BPositions, Test2BSortedDocValuesOrds, 
TestPackedTokenAttributeImpl, TestConjunctionDISI, 
TestOneMergeWrappingMergePolicy, TestLongPostings, TestRadixSelector, 
TestMultiLevelSkipList, TestSimpleFSDirectory, TestFilterWeight, 
TestPerFieldPostingsFormat2, TestSpanNearQuery, TestMinShouldMatch2, 
TestSameTokenSamePosition, TermInSetQueryTest, TestQueryRescorer, 
TestSimilarity2, TestStressAdvance, TestLazyProxSkipping, TestArrayUtil, 
Test2BPagedBytes, TestFilterDirectory, Test2BBinaryDocValues, TestNRTThreads, 
TestConjunctions, TestBooleanSimilarity, TestDocValues, TestTwoPhaseCommitTool, 
TestIndexWriterDeleteByQuery, TestCachingTokenFilter, TestByteBlockPool, 
TestFieldReuse, TestPolygon2D, TestSloppyMath, TestForUtil, 
TestCharsRefBuilder, TestPayloadsOnVectors, TestAllFilesHaveChecksumFooter, 
MultiCollectorTest, TestLogMergePolicy, TestDisjunctionMaxQuery, 
TestPerFieldDocValuesFormat, TestDeletionPolicy, TestBooleanOr, 
TestFlushByRamOrCountsPolicy, TestBoolean2, TestTermsEnum, 
TestPrefixCodedTerms, TestAutomatonQuery, TestSpanMultiTermQueryWrapper, 
TestSearchForDuplicates, TestIndexFileDeleter, TestPrefixQuery, 
TestBlockPostingsFormat3, TestLucene50StoredFieldsFormat, 
TestLucene60FieldInfoFormat, TestLucene60PointsFormat, TestFieldType, 
TestGeoUtils, TestAllFilesCheckIndexHeader, TestAllFilesDetectTruncation, 
TestBinaryDocValuesUpdates]
   [junit4] Completed [382/454 (1!)] on J2 in 4.46s, 29 tests, 1 failure, 1 
skipped <<< FAILURES!

[...truncated 36 lines...]
   [junit4] Suite: org.apache.lucene.index.TestMixedDocValuesUpdates
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestMixedDocValuesUpdates -Dtests.method=testManyReopensAndFields 
-Dtests.seed=4F2C0D8C11091CBF -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=de-DE -Dtests.timezone=Africa/Luanda -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.20s J2 | 
TestMixedDocValuesUpdates.testManyReopensAndFields <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: invalid binary value 
for doc=0, field=f1, reader=_c(7.0.0):C114:fieldInfosGen=1:dvGen=1 expected:<5> 
but was:<4>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4F2C0D8C11091CBF:79D06FA390FC7FA3]:0)
   [junit4]    >        at 
org.apache.lucene.index.TestMixedDocValuesUpdates.testManyReopensAndFields(TestMixedDocValuesUpdates.java:141)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] IGNOR/A 0.00s J2 | TestMixedDocValuesUpdates.testTonsOfUpdates
   [junit4]    > Assumption #1: 'nightly' test group is disabled (@Nightly())
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{updKey=FSTOrd50, id=PostingsFormat(name=Asserting), 
key=PostingsFormat(name=Asserting)}, 
docValues:{cf=DocValuesFormat(name=Memory), f=DocValuesFormat(name=Lucene70), 
f0=DocValuesFormat(name=Lucene70), f1=DocValuesFormat(name=Memory), 
id=DocValuesFormat(name=Memory), f2=DocValuesFormat(name=Asserting), 
cf0=DocValuesFormat(name=Direct), f3=DocValuesFormat(name=Direct), 
f4=DocValuesFormat(name=Lucene70), key=DocValuesFormat(name=Memory), 
cf1=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=497, 
maxMBSortInHeap=6.957445509607828, sim=RandomSimilarity(queryNorm=false): {}, 
locale=de-DE, timezone=Africa/Luanda
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=124802976,total=275775488
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestComplexExplanationsOfNonMatches, TestIOUtils, TestDemoParallelLeafReader, 
TestIndexCommit, TestMultiset, TestRollingBuffer, TestMutablePointsReaderUtils, 
TestRamUsageEstimator, TestSegmentTermDocs, TestIndexSearcher, 
TestAttributeSource, TestTopDocsCollector, Test2BBKDPoints, 
TestFastCompressionMode, TestNumericUtils, TestSpanSearchEquivalence, 
TestTopDocsMerge, TestStopFilter, TestLucene70NormsFormat, TestAutomaton, 
TestRegexpQuery, TestSortedSetDocValues, TestIndexWriterReader, 
TestBufferedIndexInput, TestIntsRef, TestGeoEncodingUtils, 
TestIndexWriterLockRelease, TestScorerPerf, TestLSBRadixSorter, 
TestUsageTrackingFilterCachingPolicy, TestIndexWriterCommit, 
TestLongRangeFieldQueries, TestSimpleExplanationsOfNonMatches, 
TestLucene50StoredFieldsFormatHighCompression, TestSentinelIntSet, 
TestSegmentTermEnum, Test2BPositions, Test2BSortedDocValuesOrds, 
TestPackedTokenAttributeImpl, TestConjunctionDISI, 
TestOneMergeWrappingMergePolicy, TestLongPostings, TestRadixSelector, 
TestMultiLevelSkipList, TestSimpleFSDirectory, TestFilterWeight, 
TestPerFieldPostingsFormat2, TestSpanNearQuery, TestMinShouldMatch2, 
TestSameTokenSamePosition, TermInSetQueryTest, TestQueryRescorer, 
TestSimilarity2, TestStressAdvance, TestLazyProxSkipping, TestArrayUtil, 
Test2BPagedBytes, TestFilterDirectory, Test2BBinaryDocValues, TestNRTThreads, 
TestConjunctions, TestBooleanSimilarity, TestDocValues, TestTwoPhaseCommitTool, 
TestIndexWriterDeleteByQuery, TestCachingTokenFilter, TestByteBlockPool, 
TestFieldReuse, TestPolygon2D, TestSloppyMath, TestForUtil, 
TestCharsRefBuilder, TestPayloadsOnVectors, TestAllFilesHaveChecksumFooter, 
MultiCollectorTest, TestLogMergePolicy, TestDisjunctionMaxQuery, 
TestPerFieldDocValuesFormat, TestDeletionPolicy, TestBooleanOr, 
TestFlushByRamOrCountsPolicy, TestBoolean2, TestTermsEnum, 
TestPrefixCodedTerms, TestAutomatonQuery, TestSpanMultiTermQueryWrapper, 
TestSearchForDuplicates, TestIndexFileDeleter, TestPrefixQuery, 
TestBlockPostingsFormat3, TestLucene50StoredFieldsFormat, 
TestLucene60FieldInfoFormat, TestLucene60PointsFormat, TestFieldType, 
TestGeoUtils, TestAllFilesCheckIndexHeader, TestAllFilesDetectTruncation, 
TestBinaryDocValuesUpdates, TestCustomTermFreq, TestExceedMaxTermLength, 
TestExitableDirectoryReader, TestIndexManyDocuments, TestIndexReaderClose, 
TestIndexWriterMaxDocs, TestIndexWriterThreadsToSegments, TestInfoStream, 
TestMergePolicyWrapper, TestMixedDocValuesUpdates]
   [junit4] Completed [394/454 (2!)] on J2 in 2.50s, 4 tests, 1 failure, 1 
skipped <<< FAILURES!

[...truncated 9370 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_28EFB5E8921CC2C-001/init-core-data-001
   [junit4]   2> 41285 WARN  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=39 numCloses=39
   [junit4]   2> 41286 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 41287 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 41301 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 41302 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 41380 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 41497 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] o.a.s.s.IndexSchema 
[null] Schema name=test
   [junit4]   2> 41551 WARN  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class 
[solr.WordDelimiterFilterFactory]. Please consult documentation how to replace 
it accordingly.
   [junit4]   2> 41702 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] o.a.s.s.IndexSchema 
Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 41857 WARN  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 41857 WARN  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 41857 WARN  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 41864 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 41903 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 41931 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 42079 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 42098 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 42099 WARN  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr 
metrics via JMX.
   [junit4]   2> 42099 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 42099 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_28EFB5E8921CC2C-001/init-core-data-001/]
   [junit4]   2> 42104 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=16, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=65.0537109375, 
floorSegmentMB=2.185546875, forceMergeDeletesPctAllowed=13.500416043623717, 
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.42385371952405027
   [junit4]   2> 42224 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 42224 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 42225 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 42225 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 42226 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=19, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8487954399865149]
   [junit4]   2> 42226 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@bfe81ac[collection1] 
main]
   [junit4]   2> 42227 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 42261 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 42305 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 42305 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 42305 INFO  (coreLoadExecutor-384-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1571892141101154304
   [junit4]   2> 42306 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 42306 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 42306 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 42306 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 42306 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 42307 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 42307 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 42307 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 42308 INFO  
(searcherExecutor-385-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@bfe81ac[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 42311 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 42314 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 42315 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 42316 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5e6b4fcd[collection1] realtime]
   [junit4]   2> 42316 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 42317 ERROR 
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException:
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 42319 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 42356 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 42415 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 42531 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 42542 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 42543 WARN  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr 
metrics via JMX.
   [junit4]   2> 42543 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_28EFB5E8921CC2C-001/init-core-data-001/]
   [junit4]   2> 42605 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 42605 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 42606 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@20e807af[collection1] main]
   [junit4]   2> 42607 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 42608 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 42611 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 42612 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1571892141423067136
   [junit4]   2> 42614 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 42615 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 42615 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 42615 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 42616 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 42625 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 42626 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 42627 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 42628 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 42628 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@20e807af[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 42629 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=49, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=27.357421875, 
floorSegmentMB=1.3427734375, forceMergeDeletesPctAllowed=26.234674561421986, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 42629 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 42630 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@623cc7f9[collection1] main]
   [junit4]   2> 42630 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5025a92
   [junit4]   2> 42632 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@623cc7f9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 42633 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=84040338
   [junit4]   2> 42664 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 
(1571892141448232960)]} 0 28
   [junit4]   2> 42665 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 46664 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 46670 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3b09b6d4[collection1] main]
   [junit4]   2> 46673 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3b09b6d4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 46673 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 46922 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 
(1571892145941381120)]} 0 4286
   [junit4]   2> 46929 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=6
   [junit4]   2> 46930 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 
(-1571892145950818304)]} 0 4294
   [junit4]   2> 46931 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 48930 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 48935 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@383e9a1e[collection1] main]
   [junit4]   2> 48936 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@383e9a1e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C1)))}
   [junit4]   2> 48936 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 48940 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 48941 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 48945 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148060553216)]} 0 6309
   [junit4]   2> 48946 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148063698944)]} 0 6310
   [junit4]   2> 48946 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148064747520)]} 0 6310
   [junit4]   2> 48947 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148064747521)]} 0 6311
   [junit4]   2> 48947 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148065796096)]} 0 6311
   [junit4]   2> 48947 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148065796097)]} 0 6311
   [junit4]   2> 48948 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148066844672)]} 0 6312
   [junit4]   2> 48948 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148066844673)]} 0 6312
   [junit4]   2> 48949 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148066844674)]} 0 6313
   [junit4]   2> 48949 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892148067893248)]} 0 6313
   [junit4]   2> 48950 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 48950 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148068941824)]} 0 6314
   [junit4]   2> 48950 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148068941825)]} 0 6314
   [junit4]   2> 48951 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148069990400)]} 0 6315
   [junit4]   2> 48951 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148069990401)]} 0 6315
   [junit4]   2> 48951 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148069990402)]} 0 6315
   [junit4]   2> 48951 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148069990403)]} 0 6315
   [junit4]   2> 48952 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148069990404)]} 0 6316
   [junit4]   2> 48952 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148071038976)]} 0 6316
   [junit4]   2> 48952 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148071038977)]} 0 6316
   [junit4]   2> 48952 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 
(-1571892148071038978)]} 0 6316
   [junit4]   2> 48952 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 49950 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 49959 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@51c0302f[collection1] main]
   [junit4]   2> 49959 INFO  (commitScheduler-393-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 49960 INFO  
(searcherExecutor-390-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@51c0302f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C10/9:delGen=1)))}
   [junit4]   2> 50206 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 50207 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 50207 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[28EFB5E8921CC2C]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> 50209 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
   [junit4]   2> 50210 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 50210 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@27ec9b5e[collection1] realtime]
   [junit4]   2> 50210 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 50211 ERROR 
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException:
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 50211 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 50250 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 50277 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 50389 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 50401 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 50402 WARN  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 50402 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_28EFB5E8921CC2C-001/init-core-data-001/]
   [junit4]   2> 50456 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 50456 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 50457 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4d241d9d[collection1] main]
   [junit4]   2> 50458 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 50459 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 50463 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 50464 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 50465 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 50465 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 50465 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 50465 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 50466 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 50466 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 50466 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 50467 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 50467 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=19, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=56.6474609375, 
floorSegmentMB=2.1259765625, forceMergeDeletesPctAllowed=23.80754474604005, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3743543468651652
   [junit4]   2> 50467 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4d241d9d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 50467 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 50468 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2dd15e56[collection1] main]
   [junit4]   2> 50468 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@64ff5362
   [junit4]   2> 50469 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2dd15e56[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 50469 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1694454626
   [junit4]   2> 50472 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 
(1571892149662777344)]} 0 1
   [junit4]   2> 50478 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 51978 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 51984 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@38bd273d[collection1] main]
   [junit4]   2> 51987 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@38bd273d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C1)))}
   [junit4]   2> 51988 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 52231 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 
(1571892151508271104)]} 0 1761
   [junit4]   2> 52233 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=1
   [junit4]   2> 52234 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:530&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 52235 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{delete=[529 (-1571892151513513984)]} 0 0
   [junit4]   2> 52235 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 53731 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 53739 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@293aebcb[collection1] main]
   [junit4]   2> 53740 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@293aebcb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)))}
   [junit4]   2> 53740 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 53990 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 
(1571892153350619136)]} 0 3520
   [junit4]   2> 53991 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:529&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 53992 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153354813440)]} 0 3521
   [junit4]   2> 53992 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153355862016)]} 0 3522
   [junit4]   2> 53992 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153355862017)]} 0 3522
   [junit4]   2> 53993 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153355862018)]} 0 3522
   [junit4]   2> 53993 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153356910592)]} 0 3523
   [junit4]   2> 53993 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153356910593)]} 0 3523
   [junit4]   2> 53994 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153357959168)]} 0 3523
   [junit4]   2> 53994 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153357959169)]} 0 3524
   [junit4]   2> 53995 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153357959170)]} 0 3524
   [junit4]   2> 53995 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 
(1571892153359007744)]} 0 3524
   [junit4]   2> 53995 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 55490 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 55505 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2d5fdcce[collection1] main]
   [junit4]   2> 55506 INFO  
(searcherExecutor-394-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d5fdcce[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C1)
 Uninverting(_5(7.0.0):C11/9:delGen=1)))}
   [junit4]   2> 55508 INFO  (commitScheduler-397-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 55749 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 
(1571892155196112896)]} 0 5278
   [junit4]   2> 55749 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:500&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
   [junit4]   2> 55753 INFO  
(TEST-AutoCommitTest.testMaxTime-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
   [junit4]   2> 55756 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
   [junit4]   2> 55756 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 55757 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@f4a049d[collection1] realtime]
   [junit4]   2> 55757 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 55757 ERROR 
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException:
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/core.properties
   [junit4]   2> 55758 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 55799 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 55824 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 55957 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 55968 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 55969 WARN  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 55969 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_28EFB5E8921CC2C-001/init-core-data-001/]
   [junit4]   2> 56022 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 56022 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 56023 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4687c499[collection1] main]
   [junit4]   2> 56024 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 56025 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 56029 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 56032 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=76.6015625, 
floorSegmentMB=0.4443359375, forceMergeDeletesPctAllowed=25.46158485938447, 
segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 56033 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 56033 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 56034 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 56034 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 56034 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 56034 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 56034 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 56035 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 56035 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 56035 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 56036 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4687c499[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 56036 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@612107e1[collection1] main]
   [junit4]   2> 56037 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@509dbd91
   [junit4]   2> 56038 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1352514961
   [junit4]   2> 56040 INFO  
(searcherExecutor-398-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@612107e1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 56041 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 
(1571892155502297088)]} 0 2
   [junit4]   2> 56041 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 
(1571892155504394240)]} 0 2
   [junit4]   2> 56042 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 
(1571892155505442816)]} 0 3
   [junit4]   2> 56042 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 
(1571892155505442817)]} 0 3
   [junit4]   2> 56043 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 
(1571892155505442818)]} 0 3
   [junit4]   2> 56043 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 
(1571892155506491392)]} 0 4
   [junit4]   2> 56044 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 
(1571892155506491393)]} 0 4
   [junit4]   2> 56044 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 
(1571892155507539968)]} 0 5
   [junit4]   2> 56044 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 
(1571892155507539969)]} 0 5
   [junit4]   2> 56045 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 
(1571892155508588544)]} 0 6
   [junit4]   2> 56045 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 
(1571892155508588545)]} 0 6
   [junit4]   2> 56046 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 
(1571892155509637120)]} 0 6
   [junit4]   2> 56046 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 
(1571892155509637121)]} 0 7
   [junit4]   2> 56047 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 
(1571892155509637122)]} 0 7
   [junit4]   2> 56047 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:1&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 56048 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 
(1571892155511734272)]} 0 9
   [junit4]   2> 56049 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 
(1571892155511734273)]} 0 9
   [junit4]   2> 56049 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:14&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
   [junit4]   2> 56050 ERROR 
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
   [junit4]   2>        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2>        request was:q=id:14&qt=&start=0&rows=20&version=2.2
   [junit4]   2> 56050 ERROR 
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 REQUEST FAILED: 
q=id:14&qt=&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST 
FAILED: xpath=//result[@numFound=1]
   [junit4]   2>        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2>        request was:q=id:14&qt=&start=0&rows=20&version=2.2
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:871)
   [junit4]   2>        at 
org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:225)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:498)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 56051 INFO  
(TEST-AutoCommitTest.testMaxDocs-seed#[28EFB5E8921CC2C]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest 
-Dtests.method=testMaxDocs -Dtests.seed=28EFB5E8921CC2C -Dtests.multiplier=2 
-Dtests.slow=true -Dtests.locale=el-CY -Dtests.timezone=America/Cordoba 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.30s J2 | AutoCommitTest.testMaxDocs <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during 
query
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([28EFB5E8921CC2C:BB0F2D81A5CBC8A6]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:878)
   [junit4]    >        at 
org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:225)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound=1]
   [junit4]    >        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]    > </response>
   [junit4]    >        request was:q=id:14&qt=&start=0&rows=20&version=2.2
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:871)
   [junit4]    >        ... 40 more
   [junit4]   2> 56053 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 56053 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    x:collection1] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=660391267
   [junit4]   2> 56053 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 56053 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 56053 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 56054 INFO  
(SUITE-AutoCommitTest-seed#[28EFB5E8921CC2C]-worker) [    x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 56054 INFO  (commitScheduler-401-thread-1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 56085 INFO  
(coreCloseExecutor-402-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7f505c92
   [junit4]   2> 56085 INFO  
(coreCloseExecutor-402-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 56093 INFO  (commitScheduler-401-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@56784588[collection1] main]
   [junit4]   2> 56094 INFO  
(coreCloseExecutor-402-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5f9bde07 commitCommandVersion:0
   [junit4]   2> 56094 ERROR (commitScheduler-401-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker auto commit 
error...:org.apache.solr.common.SolrException: Error opening new searcher
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:2065)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:2185)
   [junit4]   2>        at 
org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:702)
   [junit4]   2>        at 
org.apache.solr.update.CommitTracker.run(CommitTracker.java:222)
   [junit4]   2>        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>        at 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
   [junit4]   2>        at 
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Caused by: org.apache.solr.common.SolrException: 
openNewSearcher called on closed core
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:2052)
   [junit4]   2>        ... 10 more
   [junit4]   2> 
   [junit4]   2> 56105 INFO  
(coreCloseExecutor-402-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=2135973010
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.update.AutoCommitTest_28EFB5E8921CC2C-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=true): {}, locale=el-CY, timezone=America/Cordoba
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=108178400,total=304087040
   [junit4]   2> NOTE: All tests run in this JVM: [TestXmlQParserPlugin, 
TestFoldingMultitermQuery, TestSuggestSpellingConverter, TestRecovery, 
SignatureUpdateProcessorFactoryTest, HdfsRestartWhileUpdatingTest, 
AutoCommitTest]
   [junit4] Completed [23/724 (1!)] on J2 in 14.85s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 45644 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to