lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_144) - Build # 6936 - Still unstable!
Date Mon, 02 Oct 2017 17:37:36 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6936/
Java: 64bit/jdk1.8.0_144 -XX:-UseCompressedOops -XX:+UseParallelGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.lucene.index.TestStressIndexing2

Error Message:
Could not remove the following files (in the order of attempts):    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001\tempDir-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001\tempDir-001    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
   C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001\tempDir-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001\tempDir-001
   C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001

	at __randomizedtesting.SeedInfo.seed([557C0623B6231573]:0)
	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
	at org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
	at com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	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([C8B5C6390F32CC04:713410E623D8C88E]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:884)
	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:1737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
	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:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
	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:877)
	... 40 more




Build Log:
[...truncated 1143 lines...]
   [junit4] Suite: org.apache.lucene.index.TestStressIndexing2
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {f94=PostingsFormat(name=Asserting), f93=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f30=PostingsFormat(name=LuceneVarGapFixedInterval), f74=PostingsFormat(name=LuceneVarGapFixedInterval), f52=PostingsFormat(name=LuceneVarGapFixedInterval), f51=PostingsFormat(name=Memory), f73=PostingsFormat(name=Memory), f54=PostingsFormat(name=Asserting), f31=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f97=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f12=PostingsFormat(name=LuceneVarGapFixedInterval), f78=PostingsFormat(name=LuceneVarGapFixedInterval), f33=PostingsFormat(name=Memory), f55=PostingsFormat(name=Memory), f11=PostingsFormat(name=Memory), f99=PostingsFormat(name=Memory), f58=PostingsFormat(name=Asserting), f35=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f13=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f57=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f0=PostingsFormat(name=Memory), f59=PostingsFormat(name=Memory), f18=PostingsFormat(name=Asserting), f2=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f6=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f8=PostingsFormat(name=Memory), id=PostingsFormat(name=LuceneVarGapFixedInterval), f80=PostingsFormat(name=Memory), f82=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f40=PostingsFormat(name=Memory), f21=PostingsFormat(name=Asserting), f20=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f64=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f23=PostingsFormat(name=LuceneVarGapFixedInterval), f22=PostingsFormat(name=Memory), f47=PostingsFormat(name=Asserting), f25=PostingsFormat(name=Asserting), f24=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), f48=PostingsFormat(name=Memory)}, docValues:{}, maxPointsInLeafNode=1295, maxMBSortInHeap=7.505810500302098, sim=RandomSimilarity(queryNorm=false): {f93=LM Jelinek-Mercer(0.100000), f30=IB SPL-D1, f74=IB LL-L1, f52=DFI(ChiSquared), f51=DFR I(ne)1, f73=IB LL-DZ(0.3), f54=DFR I(F)LZ(0.3), f97=DFR I(F)LZ(0.3), f12=DFR GBZ(0.3), f78=DFR I(F)3(800.0), f11=LM Jelinek-Mercer(0.700000), f33=LM Jelinek-Mercer(0.100000), f99=DFR I(n)BZ(0.3), f35=DFI(ChiSquared), f57=IB LL-L1, f13=IB SPL-D1, f0=DFR GL3(800.0), f18=DFR I(n)B3(800.0), f6=DFR I(F)B3(800.0), f8=DFR G3(800.0), f82=DFR I(ne)B2, f40=DFR I(ne)BZ(0.3), f21=DFR I(ne)3(800.0), f20=IB LL-L2, f64=DFR G3(800.0), f23=DFR I(n)B1, f22=IB LL-D1, f25=DFR GLZ(0.3), f24=DFR GB2, f48=IB LL-D2}, locale=sq, timezone=America/Dominica
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=145514032,total=239075328
   [junit4]   2> NOTE: All tests run in this JVM: [TestManyFields, TestIndexWriterCommit, TestStressAdvance, TestSimilarity, TestStopFilter, TestPayloads, TestDemo, TestLucene50StoredFieldsFormatHighCompression, TestGraphTokenStreamFiniteStrings, TestIndexedDISI, TestTotalHitCountCollector, TestSpanMultiTermQueryWrapper, TestSpanNotQuery, TestAllFilesHaveChecksumFooter, TestDisjunctionMaxQuery, TestNGramPhraseQuery, TestSloppyPhraseQuery2, TestNoDeletionPolicy, TestSimpleFSLockFactory, TestSpanBoostQuery, TestSegmentTermDocs, TestIsCurrent, TestTopFieldCollector, TestSegmentMerger, TestCharArraySet, TestBooleanScorer, TestPointValues, Test2BPostingsBytes, TestCustomSearcherSort, TestVersion, Test2BBinaryDocValues, TestIndexWriterForceMerge, Test2BBKDPoints, TestTryDelete, TestBasics, TestTermsEnum2, TestMatchNoDocsQuery, TestGrowableByteArrayDataOutput, TestTerms, TestIndexWriterOnJRECrash, TestIntRangeFieldQueries, TestFixedLengthBytesRefArray, TestMultiCollector, TestIndexOrDocValuesQuery, TestCachingTokenFilter, TestParallelTermEnum, TestIndexWriterThreadsToSegments, TestPointQueries, TestRateLimiter, TestMergedIterator, TestEarlyTermination, TestSizeBoundedForceMerge, TestPersistentSnapshotDeletionPolicy, TestLucene70NormsFormat, Test2BDocs, TestSpanTermQuery, TestDoubleValuesSource, TestDocIDMerger, TestPerFieldPostingsFormat2, TestApproximationSearchEquivalence, TestMultiFields, TestStringMSBRadixSorter, TestDirectory, TestPhraseQuery, TestStressIndexing, TestMultiTermQueryRewrites, TestUsageTrackingFilterCachingPolicy, TestFastDecompressionMode, TestMultiLevelSkipList, TestMaxTermFrequency, TestFilterWeight, TestPackedInts, TestBM25Similarity, TestInfoStream, TestDuelingCodecsAtNight, TestRoaringDocIdSet, TestDocumentsWriterStallControl, TestFuzzyQuery, TestFieldType, TestByteSlices, TestIndexWriterLockRelease, TestReaderClosed, TestPagedBytes, TestLongPostings, TestIndexWriterConfig, TestSortedSetSelector, TestWildcard, TestDeletionPolicy, TestAutomatonQuery, TestTransactionRollback, TestLongRangeFieldQueries, TestGraphTokenizers, TestReqExclBulkScorer, TestControlledRealTimeReopenThread, TestPolygon2D, TestIntsRef, TestDocValuesScoring, TestSimpleSearchEquivalence, TestBytesStore, TestDocIdsWriter, TestComplexExplanationsOfNonMatches, Test2BNumericDocValues, TestOperations, TestWeakIdentityMap, TestSearcherManager, LimitedFiniteStringsIteratorTest, TestDirectoryReaderReopen, TestNRTReaderCleanup, TestBufferedChecksum, TestNearSpansOrdered, TestTransactions, TestCachingCollector, TestLucene60FieldInfoFormat, TestIndexWriter, TestIndexWriterReader, TestIndexWriterMerging, TestShardSearching, TestIndexWriterExceptions, TestNRTThreads, TestBoolean2, TestSearchWithThreads, TestHighCompressionMode, TestNeverDelete, TestTopDocsMerge, TestRollingUpdates, TestSnapshotDeletionPolicy, TestNRTReaderWithThreads, TestPerFieldPostingsFormat, TestSimpleExplanations, TestDirectoryReader, TestLockFactory, TestBagOfPositions, TestOmitNorms, TestFixedBitSet, TestNRTCachingDirectory, TestParallelCompositeReader, TestIndexWriterNRTIsCurrent, TestMultiThreadTermVectors, TestMultiDocValues, TestToken, TestFieldsReader, TestDocValuesIndexing, TestSimilarityBase, TestBooleanMinShouldMatch, TestIndexWriterOnDiskFull, TestCustomNorms, TestPostingsOffsets, TestFlex, TestUTF32ToUTF8, TestFastCompressionMode, TestStressIndexing2]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestStressIndexing2 -Dtests.seed=557C0623B6231573 -Dtests.slow=true -Dtests.locale=sq -Dtests.timezone=America/Dominica -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestStressIndexing2 (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001\tempDir-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001\tempDir-001
   [junit4]    >    C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\J1\temp\lucene.index.TestStressIndexing2_557C0623B6231573-001
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([557C0623B6231573]:0)
   [junit4]    > 	at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [279/454 (1!)] on J1 in 0.57s, 3 tests, 1 error <<< FAILURES!

[...truncated 10950 lines...]
   [junit4] Suite: org.apache.solr.update.AutoCommitTest
   [junit4]   2> 677945 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_C8B5C6390F32CC04-001\init-core-data-001
   [junit4]   2> 677947 WARN  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
   [junit4]   2> 677947 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 677950 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 677951 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 677960 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 678017 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 678036 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 678138 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 678216 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 678221 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 678221 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 678222 INFO  (coreLoadExecutor-2085-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 678223 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 678274 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 678291 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 678382 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 678391 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true
   [junit4]   2> 678391 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 678391 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 678391 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_C8B5C6390F32CC04-001\init-core-data-001\]
   [junit4]   2> 678393 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=655374545, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 678479 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 678479 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 678480 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 678480 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 678481 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=38.7861328125, floorSegmentMB=1.1875, forceMergeDeletesPctAllowed=9.037731234641162, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.10549215494550879
   [junit4]   2> 678483 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6136eafe[collection1] main]
   [junit4]   2> 678483 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 678491 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 678586 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 678586 INFO  (coreLoadExecutor-2085-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580164457879830528
   [junit4]   2> 678588 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 678589 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 678590 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 678590 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 678590 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 678591 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
   [junit4]   2> 678591 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 678591 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 678593 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 678593 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d3ca70e[collection1] realtime]
   [junit4]   2> 678593 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] 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 2
   [junit4]   2> 678594 ERROR (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties
   [junit4]   2> 678595 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 678595 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 678642 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 678644 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 678648 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 678652 INFO  (searcherExecutor-2086-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6136eafe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 678661 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 678750 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 678760 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 678830 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 678830 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_C8B5C6390F32CC04-001\init-core-data-001\]
   [junit4]   2> 678919 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 678919 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 678922 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6048a412[collection1] main]
   [junit4]   2> 678923 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 678924 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 678941 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 678941 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 678942 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1580164458253123584
   [junit4]   2> 678943 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 678943 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 678943 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 678948 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 678949 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 678949 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=22.2470703125, floorSegmentMB=0.62890625, forceMergeDeletesPctAllowed=18.471665836514113, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 678949 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 678950 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 678950 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 678950 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 678952 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6048a412[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 678952 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@69cd02d2[collection1] main]
   [junit4]   2> 678954 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@69cd02d2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 678954 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7d7af4c4
   [junit4]   2> 678954 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2105210052
   [junit4]   2> 678954 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16e0b210: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@32d7e4e7
   [junit4]   2> 678980 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1580164458276192256)]} 0 15
   [junit4]   2> 678980 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 682981 INFO  (commitScheduler-2094-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> 682991 INFO  (commitScheduler-2094-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3147d12b[collection1] main]
   [junit4]   2> 682991 INFO  (commitScheduler-2094-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 682994 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3147d12b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))}
   [junit4]   2> 683010 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1580164462517682176)]} 0 4046
   [junit4]   2> 683010 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 683011 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[529 (-1580164462519779328)]} 0 4046
   [junit4]   2> 683011 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 685012 INFO  (commitScheduler-2094-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> 685013 INFO  (commitScheduler-2094-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@23871ba2[collection1] main]
   [junit4]   2> 685014 INFO  (commitScheduler-2094-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 685015 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23871ba2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C1)))}
   [junit4]   2> 685017 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 685017 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464628465664)]} 0 6059
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514240)]} 0 6059
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514241)]} 0 6059
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514242)]} 0 6060
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514243)]} 0 6060
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514244)]} 0 6060
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514245)]} 0 6060
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514246)]} 0 6060
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514247)]} 0 6060
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164464629514248)]} 0 6061
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514249)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514250)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514251)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514252)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514253)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514254)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514255)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514256)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514257)]} 0 6062
   [junit4]   2> 685023 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[530 (-1580164464629514258)]} 0 6063
   [junit4]   2> 685027 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 686027 INFO  (commitScheduler-2094-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> 686041 INFO  (commitScheduler-2094-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7e817cff[collection1] main]
   [junit4]   2> 686046 INFO  (searcherExecutor-2091-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7e817cff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C10/9:delGen=1)))}
   [junit4]   2> 686046 INFO  (commitScheduler-2094-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 686291 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 686291 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    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> 686291 INFO  (TEST-AutoCommitTest.testCommitWithin-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> 686294 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
   [junit4]   2> 686294 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 686294 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@523ff57c[collection1] realtime]
   [junit4]   2> 686295 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    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 0
   [junit4]   2> 686295 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties
   [junit4]   2> 686296 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 686353 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 686388 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 686510 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 686535 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 686556 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 686556 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_C8B5C6390F32CC04-001\init-core-data-001\]
   [junit4]   2> 686674 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 686674 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 686675 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71cbf604[collection1] main]
   [junit4]   2> 686676 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 686678 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 686687 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 686687 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 686688 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 686688 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 686688 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 686688 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 686688 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 686689 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 686689 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 686689 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 686690 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=40, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8025142028940783]
   [junit4]   2> 686690 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 686692 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71cbf604[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 686692 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1439fe95[collection1] main]
   [junit4]   2> 686692 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@469c988f
   [junit4]   2> 686692 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1184667791
   [junit4]   2> 686692 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1fde978f: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@32d7e4e7
   [junit4]   2> 686702 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1439fe95[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[0 (1580164466390073344)]} 0 0
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[1 (1580164466391121920)]} 0 1
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[2 (1580164466391121921)]} 0 1
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[3 (1580164466391121922)]} 0 1
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[4 (1580164466391121923)]} 0 2
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[5 (1580164466391121924)]} 0 2
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[6 (1580164466391121925)]} 0 2
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[7 (1580164466391121926)]} 0 2
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[8 (1580164466391121927)]} 0 3
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[9 (1580164466391121928)]} 0 3
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[10 (1580164466391121929)]} 0 3
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[11 (1580164466391121930)]} 0 3
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[12 (1580164466391121931)]} 0 3
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[13 (1580164466391121932)]} 0 3
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    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> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[14 (1580164466391121933)]} 0 4
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[15 (1580164466391121934)]} 0 4
   [junit4]   2> 686703 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    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> 686707 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    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> 686710 ERROR (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    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:877)
   [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:1737)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
   [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:943)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
   [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> 686711 INFO  (TEST-AutoCommitTest.testMaxDocs-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest -Dtests.method=testMaxDocs -Dtests.seed=C8B5C6390F32CC04 -Dtests.slow=true -Dtests.locale=ar-LY -Dtests.timezone=Africa/Timbuktu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.42s J1 | AutoCommitTest.testMaxDocs <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([C8B5C6390F32CC04:713410E623D8C88E]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:884)
   [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:877)
   [junit4]    > 	... 40 more
   [junit4]   2> 686716 INFO  (commitScheduler-2098-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> 686718 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
   [junit4]   2> 686723 INFO  (commitScheduler-2098-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ee4dd24[collection1] main]
   [junit4]   2> 686723 INFO  (commitScheduler-2098-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 686725 INFO  (searcherExecutor-2095-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ee4dd24[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C16)))}
   [junit4]   2> 686725 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 686726 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@580c8456[collection1] realtime]
   [junit4]   2> 686726 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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 8
   [junit4]   2> 686727 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties:java.nio.file.NoSuchFileException: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\core.properties
   [junit4]   2> 686728 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 686828 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 686848 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 686971 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 686994 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 687021 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f9760c0
   [junit4]   2> 687021 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_C8B5C6390F32CC04-001\init-core-data-001\]
   [junit4]   2> 687229 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 687229 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 687231 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6aea1539[collection1] main]
   [junit4]   2> 687231 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 687234 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 687246 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 687247 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 687249 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 687249 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 687249 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 687249 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 687249 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 687250 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=75.9130859375, floorSegmentMB=1.6201171875, forceMergeDeletesPctAllowed=8.077745054642008, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5941660324455341
   [junit4]   2> 687250 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 687250 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 687251 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 687255 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6aea1539[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 687255 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 687255 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71bbd2b0[collection1] main]
   [junit4]   2> 687258 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71bbd2b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 687258 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@610d2aa7
   [junit4]   2> 687258 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1628252839
   [junit4]   2> 687258 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12eb342c: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@32d7e4e7
   [junit4]   2> 687270 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[529 (1580164466984615936)]} 0 0
   [junit4]   2> 687270 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 688770 INFO  (commitScheduler-2102-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> 688783 INFO  (commitScheduler-2102-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@66bafa30[collection1] main]
   [junit4]   2> 688785 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@66bafa30[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(8.0.0):C1)))}
   [junit4]   2> 688785 INFO  (commitScheduler-2102-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 688813 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[530 (1580164468603617280)]} 0 1544
   [junit4]   2> 688814 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 688814 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 688816 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{delete=[529 (-1580164468606763008)]} 0 1
   [junit4]   2> 688816 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 690314 INFO  (commitScheduler-2102-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> 690316 INFO  (commitScheduler-2102-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@374ebb7e[collection1] main]
   [junit4]   2> 690316 INFO  (commitScheduler-2102-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 690318 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@374ebb7e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C1)))}
   [junit4]   2> 690346 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[550 (1580164470211084288)]} 0 3077
   [junit4]   2> 690346 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132864)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132865)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132866)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132867)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132868)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132869)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132870)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132871)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132872)]} 0 3078
   [junit4]   2> 690347 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[500 (1580164470212132873)]} 0 3078
   [junit4]   2> 690348 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 691847 INFO  (commitScheduler-2102-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> 691848 INFO  (commitScheduler-2102-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5373c63f[collection1] main]
   [junit4]   2> 691849 INFO  (commitScheduler-2102-thread-1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 691850 INFO  (searcherExecutor-2099-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5373c63f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(8.0.0):C1) Uninverting(_6(8.0.0):C11/9:delGen=1)))}
   [junit4]   2> 691852 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[531 (1580164471790239744)]} 0 4583
   [junit4]   2> 691852 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    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> 691853 INFO  (TEST-AutoCommitTest.testMaxTime-seed#[C8B5C6390F32CC04]) [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
   [junit4]   2> 691853 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 691853 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=399063200
   [junit4]   2> 691853 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 691853 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f25997c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7ac53a6
   [junit4]   2> 691857 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 691857 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@35afa113: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10db70e2
   [junit4]   2> 691860 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 691860 INFO  (SUITE-AutoCommitTest-seed#[C8B5C6390F32CC04]-worker) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2994f700: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1835f2e6
   [junit4]   2> 691861 INFO  (coreCloseExecutor-2103-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4d3ee2d5
   [junit4]   2> 691861 INFO  (coreCloseExecutor-2103-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1295966933
   [junit4]   2> 691861 INFO  (coreCloseExecutor-2103-thread-1-processing-x:collection1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1ff5ae9b: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@32d7e4e7
   [junit4]   2> 691871 INFO  (coreCloseExecutor-2103-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 691871 INFO  (coreCloseExecutor-2103-thread-1-processing-x:collection1) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6904d4f9 commitCommandVersion:0
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_C8B5C6390F32CC04-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {field_t=PostingsFormat(name=LuceneFixedGap), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), subject=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneFixedGap), text=PostingsFormat(name=Memory)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Asserting), id_i1=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene70), intDvoDefault=DocValuesFormat(name=Memory), range_facet_l=DocValuesFormat(name=Lucene70), timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1539, maxMBSortInHeap=6.090496929822489, sim=RandomSimilarity(queryNorm=false): {}, locale=ar-LY, timezone=Africa/Timbuktu
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=169784960,total=408420352
   [junit4]   2> NOTE: All tests run in this JVM: [ZkNodePropsTest, CdcrReplicationHandlerTest, TestStressLucene, SpellCheckComponentTest, TestHdfsUpdateLog, HttpPartitionTest, SignatureUpdateProcessorFactoryTest, DistribCursorPagingTest, TestHalfAndHalfDocValues, TestShortCircuitedRequests, SuggesterTSTTest, TestInfoStreamLogging, TestSha256AuthenticationProvider, TestRTimerTree, HdfsChaosMonkeySafeLeaderTest, TestIndexSearcher, MigrateRouteKeyTest, DocValuesMultiTest, CoreSorterTest, TestPayloadCheckQParserPlugin, DeleteInactiveReplicaTest, TestCollationField, TestBlendedInfixSuggestions, PingRequestHandlerTest, TestSubQueryTransformerDistrib, PeerSyncWithIndexFingerprintCachingTest, ClassificationUpdateProcessorFactoryTest, ActionThrottleTest, JsonLoaderTest, StatelessScriptUpdateProcessorFactoryTest, TestAddFieldRealTimeGet, BlockCacheTest, StatsComponentTest, TestHdfsBackupRestoreCore, HdfsNNFailoverTest, BasicFunctionalityTest, ZkStateWriterTest, TestRecoveryHdfs, TestBlobHandler, MoveReplicaHDFSTest, ParsingFieldUpdateProcessorsTest, CdcrVersionReplicationTest, TestManagedStopFilterFactory, TestConfigSetProperties, OpenExchangeRatesOrgProviderTest, TestSSLRandomization, HdfsSyncSliceTest, CachingDirectoryFactoryTest, GraphQueryTest, CollectionsAPISolrJTest, TestJsonFacets, TestRealTimeGet, ForceLeaderTest, TestPerFieldSimilarity, TestSQLHandler, SpellCheckCollatorWithCollapseTest, SystemInfoHandlerTest, TestFiltering, TermsComponentTest, CopyFieldTest, TestAnalyzedSuggestions, TestOnReconnectListenerSupport, TestSolrCloudWithSecureImpersonation, SimpleCollectionCreateDeleteTest, TestElisionMultitermQuery, NodeMutatorTest, TestNoOpRegenerator, SearchHandlerTest, SchemaVersionSpecificBehaviorTest, AutoCommitTest]
   [junit4] Completed [176/734 (1!)] on J1 in 14.00s, 3 tests, 1 error <<< FAILURES!

[...truncated 48698 lines...]

Mime
View raw message