You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/04/25 08:18:15 UTC
[JENKINS] Lucene-Solr-NightlyTests-8.x - Build # 423 - Still
Unstable
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-8.x/423/
3 tests failed.
FAILED: org.apache.lucene.index.TestIndexWriterDelete.testDeleteAllNoDeadLock
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([9FFDE051BF24E6CD:ACE40F4F3FE75C9B]:0)
at org.apache.lucene.index.IndexWriter.abortMerges(IndexWriter.java:2535)
at org.apache.lucene.index.IndexWriter.deleteAll(IndexWriter.java:2462)
at org.apache.lucene.index.RandomIndexWriter.deleteAll(RandomIndexWriter.java:371)
at org.apache.lucene.index.TestIndexWriterDelete.testDeleteAllNoDeadLock(TestIndexWriterDelete.java:348)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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.search.TestSearcherManager.testSearcherManager
Error Message:
Input length = 1
Stack Trace:
java.nio.charset.MalformedInputException: Input length = 1
at __randomizedtesting.SeedInfo.seed([9FFDE051BF24E6CD:93D2A8EB092D8730]:0)
at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:339)
at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178)
at java.io.InputStreamReader.read(InputStreamReader.java:184)
at java.io.BufferedReader.fill(BufferedReader.java:161)
at java.io.BufferedReader.readLine(BufferedReader.java:324)
at java.io.BufferedReader.readLine(BufferedReader.java:389)
at org.apache.lucene.util.LineFileDocs.open(LineFileDocs.java:175)
at org.apache.lucene.util.LineFileDocs.<init>(LineFileDocs.java:65)
at org.apache.lucene.util.LineFileDocs.<init>(LineFileDocs.java:69)
at org.apache.lucene.index.ThreadedIndexingAndSearchingTestCase.runTest(ThreadedIndexingAndSearchingTestCase.java:459)
at org.apache.lucene.search.TestSearcherManager.testSearcherManager(TestSearcherManager.java:62)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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.cloud.cdcr.CdcrReplicationHandlerTest.testReplicationWithBufferedUpdates
Error Message:
Timeout while trying to assert number of documents @ source_collection
Stack Trace:
java.lang.AssertionError: Timeout while trying to assert number of documents @ source_collection
at __randomizedtesting.SeedInfo.seed([CE39813856636D00:1D30D12613F0F197]:0)
at org.apache.solr.cloud.cdcr.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:277)
at org.apache.solr.cloud.cdcr.CdcrReplicationHandlerTest.testReplicationWithBufferedUpdates(CdcrReplicationHandlerTest.java:233)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1081)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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.AssertionError: expected:<100> but was:<110>
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.cdcr.BaseCdcrDistributedZkTest.assertNumDocs(BaseCdcrDistributedZkTest.java:268)
... 42 more
Build Log:
[...truncated 1837 lines...]
[junit4] Suite: org.apache.lucene.search.TestSearcherManager
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSearcherManager -Dtests.method=testSearcherManager -Dtests.seed=9FFDE051BF24E6CD -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=pt -Dtests.timezone=Europe/Samara -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.02s J0 | TestSearcherManager.testSearcherManager <<<
[junit4] > Throwable #1: java.nio.charset.MalformedInputException: Input length = 1
[junit4] > at __randomizedtesting.SeedInfo.seed([9FFDE051BF24E6CD:93D2A8EB092D8730]:0)
[junit4] > at java.nio.charset.CoderResult.throwException(CoderResult.java:281)
[junit4] > at sun.nio.cs.StreamDecoder.implRead(StreamDecoder.java:339)
[junit4] > at sun.nio.cs.StreamDecoder.read(StreamDecoder.java:178)
[junit4] > at java.io.InputStreamReader.read(InputStreamReader.java:184)
[junit4] > at java.io.BufferedReader.fill(BufferedReader.java:161)
[junit4] > at java.io.BufferedReader.readLine(BufferedReader.java:324)
[junit4] > at java.io.BufferedReader.readLine(BufferedReader.java:389)
[junit4] > at org.apache.lucene.util.LineFileDocs.open(LineFileDocs.java:175)
[junit4] > at org.apache.lucene.util.LineFileDocs.<init>(LineFileDocs.java:65)
[junit4] > at org.apache.lucene.util.LineFileDocs.<init>(LineFileDocs.java:69)
[junit4] > at org.apache.lucene.index.ThreadedIndexingAndSearchingTestCase.runTest(ThreadedIndexingAndSearchingTestCase.java:459)
[junit4] > at org.apache.lucene.search.TestSearcherManager.testSearcherManager(TestSearcherManager.java:62)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/build/core/test/J0/temp/lucene.search.TestSearcherManager_9FFDE051BF24E6CD-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {field=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{}, maxPointsInLeafNode=599, maxMBSortInHeap=7.43647095455883, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1af2d326), locale=pt, timezone=Europe/Samara
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=287799408,total=349175808
[junit4] 2> NOTE: All tests run in this JVM: [TestAxiomaticF2LOG, TestMinimize, TestBooleanMinShouldMatch, TestSetOnce, TestLucene60FieldInfoFormat, TestEarlyTermination, Test2BPostings, TestQueryBuilder, TestDocsWithFieldSet, TestXYMultiPolygonShapeQueries, TestCheckIndex, TestXYDocValuesQueries, TestAllFilesHaveChecksumFooter, TestIndexReaderClose, TestQueryVisitor, TestHugeRamFile, TestInfoStream, TestSimpleExplanationsOfNonMatches, TestRecyclingByteBlockAllocator, TestUniqueTermCount, TestDocValuesRewriteMethod, TestWeakIdentityMap, TestXYLine, Test2BNumericDocValues, Test2BSortedDocValuesOrds, TestCharArraySet, TestFileSwitchDirectory, TestSimpleFSDirectory, TestMatchesIterator, TestDocIDMerger, TestReqOptSumScorer, TestScorerPerf, TestPolygon, TestConcurrentMergeScheduler, TestDeletionPolicy, TestLucene80NormsFormatMergeInstance, TestIndexWriterWithThreads, TestFlushByRamOrCountsPolicy, TestBKDRadixSelector, TestBufferedIndexInput, TestFastCompressionMode, TestPhrasePrefixQuery, TestDistributionSPL, TestDocumentsWriterStallControl, TestCharsRef, TestBinaryTerms, TestCollectionUtil, MultiCollectorTest, TestUtil, TestRegexpRandom, TestBagOfPositions, TestSortedSetDocValues, TestPositionIncrement, TestXYLineShapeQueries, TestLSBRadixSorter, TestIndexInput, TestNeedsScores, TestSimilarity, TestIndexWriterExceptions2, TestDirectoryReaderReopen, TestRollingBuffer, TestDelegatingAnalyzerWrapper, TestFieldMaskingSpanQuery, TestAxiomaticSimilarity, TestBytesRef, TestExceedMaxTermLength, TestDisjunctionScoreBlockBoundaryPropagator, TestIndexWriterLockRelease, TestFieldsReader, TestMergeSchedulerExternal, TestDeterminism, TestManyFields, TestSearcherManager]
[junit4] Completed [426/564 (1!)] on J0 in 91.39s, 11 tests, 1 error <<< FAILURES!
[...truncated 62 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterDelete
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexWriterDelete -Dtests.method=testDeleteAllNoDeadLock -Dtests.seed=9FFDE051BF24E6CD -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=uk -Dtests.timezone=Africa/Luanda -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 2.41s J0 | TestIndexWriterDelete.testDeleteAllNoDeadLock <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([9FFDE051BF24E6CD:ACE40F4F3FE75C9B]:0)
[junit4] > at org.apache.lucene.index.IndexWriter.abortMerges(IndexWriter.java:2535)
[junit4] > at org.apache.lucene.index.IndexWriter.deleteAll(IndexWriter.java:2462)
[junit4] > at org.apache.lucene.index.RandomIndexWriter.deleteAll(RandomIndexWriter.java:371)
[junit4] > at org.apache.lucene.index.TestIndexWriterDelete.testDeleteAllNoDeadLock(TestIndexWriterDelete.java:348)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/lucene/build/core/test/J0/temp/lucene.index.TestIndexWriterDelete_9FFDE051BF24E6CD-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {field=BlockTreeOrds(blocksize=128), city=Lucene84, contents=BlockTreeOrds(blocksize=128), id=Lucene84, body=BlockTreeOrds(blocksize=128), value=PostingsFormat(name=Asserting), content=PostingsFormat(name=Asserting)}, docValues:{dv=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1370, maxMBSortInHeap=5.332420630477623, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@50dc59d7), locale=uk, timezone=Africa/Luanda
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=189903840,total=404750336
[junit4] 2> NOTE: All tests run in this JVM: [TestAxiomaticF2LOG, TestMinimize, TestBooleanMinShouldMatch, TestSetOnce, TestLucene60FieldInfoFormat, TestEarlyTermination, Test2BPostings, TestQueryBuilder, TestDocsWithFieldSet, TestXYMultiPolygonShapeQueries, TestCheckIndex, TestXYDocValuesQueries, TestAllFilesHaveChecksumFooter, TestIndexReaderClose, TestQueryVisitor, TestHugeRamFile, TestInfoStream, TestSimpleExplanationsOfNonMatches, TestRecyclingByteBlockAllocator, TestUniqueTermCount, TestDocValuesRewriteMethod, TestWeakIdentityMap, TestXYLine, Test2BNumericDocValues, Test2BSortedDocValuesOrds, TestCharArraySet, TestFileSwitchDirectory, TestSimpleFSDirectory, TestMatchesIterator, TestDocIDMerger, TestReqOptSumScorer, TestScorerPerf, TestPolygon, TestConcurrentMergeScheduler, TestDeletionPolicy, TestLucene80NormsFormatMergeInstance, TestIndexWriterWithThreads, TestFlushByRamOrCountsPolicy, TestBKDRadixSelector, TestBufferedIndexInput, TestFastCompressionMode, TestPhrasePrefixQuery, TestDistributionSPL, TestDocumentsWriterStallControl, TestCharsRef, TestBinaryTerms, TestCollectionUtil, MultiCollectorTest, TestUtil, TestRegexpRandom, TestBagOfPositions, TestSortedSetDocValues, TestPositionIncrement, TestXYLineShapeQueries, TestLSBRadixSorter, TestIndexInput, TestNeedsScores, TestSimilarity, TestIndexWriterExceptions2, TestDirectoryReaderReopen, TestRollingBuffer, TestDelegatingAnalyzerWrapper, TestFieldMaskingSpanQuery, TestAxiomaticSimilarity, TestBytesRef, TestExceedMaxTermLength, TestDisjunctionScoreBlockBoundaryPropagator, TestIndexWriterLockRelease, TestFieldsReader, TestMergeSchedulerExternal, TestDeterminism, TestManyFields, TestSearcherManager, TestDocValuesFieldUpdates, TestXYRectangle, TestIndexedDISI, TestSimpleWKTShapeParsing, TestLucene50StoredFieldsFormatHighCompression, TestAddIndexes, TestSumDocFreq, TestIntRangeFieldQueries, TestLatLonMultiLineShapeQueries, TestFieldInvertState, TestIOUtils, TestIntroSelector, TestBlockMaxConjunction, TestBooleanSimilarity, TestWANDScorer, TestRollback, TestIndexWriterDelete]
[junit4] Completed [443/564 (2!)] on J0 in 70.68s, 26 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 13351 lines...]
[junit4] Suite: org.apache.solr.cloud.cdcr.CdcrReplicationHandlerTest
[junit4] 2> 4356343 INFO (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 4356343 INFO (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/data-dir-243-001
[junit4] 2> 4356343 WARN (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=539 numCloses=539
[junit4] 2> 4356343 INFO (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 4356345 INFO (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 4356345 INFO (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 4356345 INFO (SUITE-CdcrReplicationHandlerTest-seed#[CE39813856636D00]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_f/
[junit4] 2> 4356351 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4356352 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4356352 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4356452 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer start zk server on port:46425
[junit4] 2> 4356452 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46425
[junit4] 2> 4356452 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46425
[junit4] 2> 4356452 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46425
[junit4] 2> 4356453 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4356458 INFO (zkConnectionManagerCallback-36922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4356458 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4356461 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4356461 INFO (zkConnectionManagerCallback-36924-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4356461 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4356462 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-cdcr.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 4356463 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 4356464 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4356465 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 4356466 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 4356467 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 4356467 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 4356468 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4356469 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4356470 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 4356470 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 4356471 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPartialReplicationWithTruncatedTlog
[junit4] 2> 4356569 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 4356569 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4356569 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4356569 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_191-b12
[junit4] 2> 4356570 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4356570 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4356570 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4356571 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5b4c3102{/_f,null,AVAILABLE}
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5feaeb90{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:36849}
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.Server Started @4356598ms
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard1, hostContext=/_f, hostPort=36849, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-001/cores}
[junit4] 2> 4356572 ERROR (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 4356572 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-25T07:30:14.212Z
[junit4] 2> 4356573 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4356573 INFO (zkConnectionManagerCallback-36926-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4356573 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4356674 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4356674 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-001/solr.xml
[junit4] 2> 4356678 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4356678 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4356679 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4357322 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4357322 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@154e1f96[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4357322 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@154e1f96[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4357325 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4934f503[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4357325 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4934f503[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4357326 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46425/solr
[junit4] 2> 4357327 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4357328 INFO (zkConnectionManagerCallback-36937-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4357328 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4357430 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4357431 INFO (zkConnectionManagerCallback-36939-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4357431 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4357486 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36849__f
[junit4] 2> 4357486 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.Overseer Overseer (id=73644968182677508-127.0.0.1:36849__f-n_0000000000) starting
[junit4] 2> 4357490 INFO (OverseerStateUpdate-73644968182677508-127.0.0.1:36849__f-n_0000000000) [n:127.0.0.1:36849__f ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:36849__f
[junit4] 2> 4357490 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36849__f
[junit4] 2> 4357491 INFO (OverseerStateUpdate-73644968182677508-127.0.0.1:36849__f-n_0000000000) [n:127.0.0.1:36849__f ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4357494 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4357494 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=you make it. Consider configuring authentication/authorization before exposing Solr to users internal or authorization=external. See https://s.apache.org/solrsecurity for more info. Solr is only as secure as disableddisabled
[junit4] 2> 4357509 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4357530 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4357539 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4357539 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4357540 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:36849__f ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-001/cores
[junit4] 2> 4357639 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 4357639 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4357639 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 4357639 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_191-b12
[junit4] 2> 4357640 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4357640 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4357640 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4357640 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3c3fd091{/_f,null,AVAILABLE}
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6b37c38e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37723}
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.s.Server Started @4357667ms
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {shards=shard2, hostContext=/_f, hostPort=37723, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-002/cores}
[junit4] 2> 4357641 ERROR (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 4357641 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-25T07:30:15.281Z
[junit4] 2> 4357642 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4357643 INFO (zkConnectionManagerCallback-36953-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4357643 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4357744 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4357744 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-002/solr.xml
[junit4] 2> 4357747 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4357748 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4357749 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4358448 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4358448 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@374e0714[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4358448 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@374e0714[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4358451 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@77b494ff[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4358451 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@77b494ff[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4358452 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46425/solr
[junit4] 2> 4358453 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4358454 INFO (zkConnectionManagerCallback-36964-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4358454 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4358556 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4358557 INFO (zkConnectionManagerCallback-36966-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4358557 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4358560 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4358562 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.ZkController Publish node=127.0.0.1:37723__f as DOWN
[junit4] 2> 4358563 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4358563 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37723__f
[junit4] 2> 4358563 INFO (zkCallback-36938-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4358564 INFO (zkCallback-36965-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4358565 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 4358565 WARN (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=you make it. Consider configuring authentication/authorization before exposing Solr to users internal or authorization=external. See https://s.apache.org/solrsecurity for more info. Solr is only as secure as disableddisabled
[junit4] 2> 4358580 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4358602 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4358614 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4358614 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4358615 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [n:127.0.0.1:37723__f ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-002/cores
[junit4] 2> 4358630 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4358631 INFO (zkConnectionManagerCallback-36979-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4358631 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4358632 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4358633 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46425/solr ready
[junit4] 2> 4358634 INFO (qtp14981899-69052) [n:127.0.0.1:36849__f ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=tmp_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4358636 INFO (OverseerThreadFactory-36946-thread-1-processing-n:127.0.0.1:36849__f) [n:127.0.0.1:36849__f ] o.a.s.c.a.c.CreateCollectionCmd Create collection tmp_collection
[junit4] 2> 4358739 WARN (OverseerThreadFactory-36946-thread-1-processing-n:127.0.0.1:36849__f) [n:127.0.0.1:36849__f ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (tmp_collection) without cores.
[junit4] 2> 4358742 INFO (qtp14981899-69052) [n:127.0.0.1:36849__f ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 4358743 INFO (qtp14981899-69052) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=tmp_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&wt=javabin&version=2} status=0 QTime=109
[junit4] 2> 4358744 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:36849__f&action=ADDREPLICA&collection=tmp_collection&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4358746 INFO (OverseerCollectionConfigSetProcessor-73644968182677508-127.0.0.1:36849__f-n_0000000000) [n:127.0.0.1:36849__f ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4358748 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4358750 INFO (qtp43788728-69086) [n:127.0.0.1:37723__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4358751 INFO (qtp14981899-69051) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4358752 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4358752 INFO (OverseerThreadFactory-36946-thread-2-processing-n:127.0.0.1:36849__f) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:36849__f for creating new replica of shard shard1 for collection tmp_collection
[junit4] 2> 4358753 INFO (OverseerThreadFactory-36946-thread-2-processing-n:127.0.0.1:36849__f) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 4358755 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f x:tmp_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n1&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4358756 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f x:tmp_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4359765 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 4359788 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 4359853 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 4359867 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'tmp_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 4359867 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.tmp_collection.shard1.replica_n1' (registry 'solr.core.tmp_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4359867 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[tmp_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-001/cores/tmp_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-001/cores/tmp_collection_shard1_replica_n1/data/]
[junit4] 2> 4359870 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@24aba75a
[junit4] 2> 4359951 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 4359951 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4359952 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4359952 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4359953 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7647180873586257]
[junit4] 2> 4359954 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@680f3438[tmp_collection_shard1_replica_n1] main]
[junit4] 2> 4359955 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4359955 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4359956 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4359957 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.h.CdcrBufferStateManager Created znode /collections/tmp_collection/cdcr/state/buffer
[junit4] 2> 4359958 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.h.CdcrProcessStateManager Created znode /collections/tmp_collection/cdcr/state/process
[junit4] 2> 4359958 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1664928781537640448
[junit4] 2> 4359960 INFO (searcherExecutor-36981-thread-1-processing-n:127.0.0.1:36849__f x:tmp_collection_shard1_replica_n1 c:tmp_collection s:shard1) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [tmp_collection_shard1_replica_n1] Registered new searcher Searcher@680f3438[tmp_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4359962 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tmp_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 4359962 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/tmp_collection/leaders/shard1
[junit4] 2> 4359964 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4359964 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4359964 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36849/_f/tmp_collection_shard1_replica_n1/
[junit4] 2> 4359965 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4359965 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:36849/_f/tmp_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 4359965 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/tmp_collection/leaders/shard1/leader after winning as /collections/tmp_collection/leader_elect/shard1/election/73644968182677508-core_node2-n_0000000000
[junit4] 2> 4359965 INFO (zkCallback-36938-thread-1) [ ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ tmp_collection:shard1
[junit4] 2> 4359970 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36849/_f/tmp_collection_shard1_replica_n1/ shard1
[junit4] 2> 4360072 INFO (zkCallback-36938-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4360072 INFO (zkCallback-36938-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4360073 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4360075 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n1&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1319
[junit4] 2> 4360077 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f c:tmp_collection ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:36849__f&action=ADDREPLICA&collection=tmp_collection&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1332
[junit4] 2> 4360077 INFO (qtp14981899-69051) [n:127.0.0.1:36849__f ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:37723__f&action=ADDREPLICA&collection=tmp_collection&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4360079 INFO (OverseerCollectionConfigSetProcessor-73644968182677508-127.0.0.1:36849__f-n_0000000000) [n:127.0.0.1:36849__f ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4360080 INFO (qtp14981899-69052) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.tmp_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 4360082 INFO (qtp14981899-69054) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4360083 INFO (qtp43788728-69088) [n:127.0.0.1:37723__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4360084 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.tmp_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 4360085 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4360086 INFO (qtp43788728-69089) [n:127.0.0.1:37723__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 4360087 INFO (qtp14981899-69052) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.tmp_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 4360087 INFO (OverseerThreadFactory-36946-thread-3-processing-n:127.0.0.1:36849__f) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:37723__f for creating new replica of shard shard1 for collection tmp_collection
[junit4] 2> 4360088 INFO (OverseerThreadFactory-36946-thread-3-processing-n:127.0.0.1:36849__f) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 4360089 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n3&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4360193 INFO (zkCallback-36938-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4360193 INFO (zkCallback-36938-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4360193 INFO (zkCallback-36938-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4361099 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 4361110 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 4361197 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 4361214 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'tmp_collection_shard1_replica_n3' using configuration from configset conf1, trusted=true
[junit4] 2> 4361214 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.tmp_collection.shard1.replica_n3' (registry 'solr.core.tmp_collection.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cac5042
[junit4] 2> 4361214 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrCore [[tmp_collection_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-002/cores/tmp_collection_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001/jetty-002/cores/tmp_collection_shard1_replica_n3/data/]
[junit4] 2> 4361217 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@254f0803
[junit4] 2> 4361271 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 4361271 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4361272 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4361272 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4361273 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7647180873586257]
[junit4] 2> 4361274 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39bc466a[tmp_collection_shard1_replica_n3] main]
[junit4] 2> 4361275 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4361275 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4361276 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4361277 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1664928782920712192
[junit4] 2> 4361279 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 r:core_node2 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request [tmp_collection_shard1_replica_n1] webapp=/_f path=/cdcr params={action=LASTPROCESSEDVERSION&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 4361279 INFO (searcherExecutor-36988-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.SolrCore [tmp_collection_shard1_replica_n3] Registered new searcher Searcher@39bc466a[tmp_collection_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4361287 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/tmp_collection/terms/shard1 to Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 4361287 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/tmp_collection/leaders/shard1
[junit4] 2> 4361290 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.ZkController Core needs to recover:tmp_collection_shard1_replica_n3
[junit4] 2> 4361291 INFO (updateExecutor-36960-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 4361291 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 4361293 INFO (qtp43788728-69087) [n:127.0.0.1:37723__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=tmp_collection_shard1_replica_n3&action=CREATE&collection=tmp_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1203
[junit4] 2> 4361293 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy startupVersions is empty
[junit4] 2> 4361294 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 r:core_node2 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request [tmp_collection_shard1_replica_n1] webapp=/_f path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 4361294 INFO (qtp14981899-69055) [n:127.0.0.1:36849__f c:tmp_collection s:shard1 r:core_node2 x:tmp_collection_shard1_replica_n1 ] o.a.s.c.S.Request [tmp_collection_shard1_replica_n1] webapp=/_f path=/admin/ping params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 4361294 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[tmp_collection_shard1_replica_n3]
[junit4] 2> 4361294 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 4361294 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Publishing state of core [tmp_collection_shard1_replica_n3] as recovering, leader is [http://127.0.0.1:36849/_f/tmp_collection_shard1_replica_n1/] and I am [http://127.0.0.1:37723/_f/tmp_collection_shard1_replica_n3/]
[junit4] 2> 4361295 INFO (qtp14981899-69051) [n:127.0.0.1:36849__f c:tmp_collection ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:37723__f&action=ADDREPLICA&collection=tmp_collection&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1217
[junit4] 2> 4361295 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Sending prep recovery command to [http://127.0.0.1:36849/_f]; [WaitForState: action=PREPRECOVERY&core=tmp_collection_shard1_replica_n1&nodeName=127.0.0.1:37723__f&coreNodeName=core_node4&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 4361296 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f x:tmp_collection_shard1_replica_n1 ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node4, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 4361296 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=tmp_collection, shard=shard1, thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:37723__f, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:37723/_f",
[junit4] 2> "node_name":"127.0.0.1:37723__f",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 4361296 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=tmp_collection, shard=shard1, thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:37723__f, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:37723/_f",
[junit4] 2> "node_name":"127.0.0.1:37723__f",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 4361296 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=tmp_collection, shard=shard1, thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:37723__f, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:37723/_f",
[junit4] 2> "node_name":"127.0.0.1:37723__f",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 2> 4361396 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationWithTruncatedTlog-seed#[CE39813856636D00]) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: tmp_collection failOnTimeout:true timeout (sec):330
[junit4] 1> replica:core_node4 rstate:down live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:down live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:down live:true
[junit4] 2> 4361397 INFO (zkCallback-36938-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4361397 INFO (zkCallback-36965-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4361397 INFO (zkCallback-36938-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4361397 INFO (zkCallback-36938-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4361397 INFO (zkCallback-36965-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 4361397 INFO (zkCallback-36965-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/tmp_collection/state.json] for collection [tmp_collection] has occurred - updating... (live nodes size: [2])
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:recovering live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:recovering live:true
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:recovering live:true
[junit4] 2> 4361397 INFO (watches-36940-thread-1) [n:127.0.0.1:36849__f ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=tmp_collection, shard=shard1, thisCore=tmp_collection_shard1_replica_n1, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=recovering, localState=active, nodeName=127.0.0.1:37723__f, coreNodeName=core_node4, onlyIfActiveCheckResult=false, nodeProps: core_node4:{
[junit4] 2> "core":"tmp_collection_shard1_replica_n3",
[junit4] 2> "base_url":"http://127.0.0.1:37723/_f",
[junit4] 2> "node_name":"127.0.0.1:37723__f",
[junit4] 2> "state":"recovering",
[junit4] 2> "type":"NRT"}
[junit4] 2> 4361397 INFO (qtp14981899-69053) [n:127.0.0.1:36849__f ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={nodeName=127.0.0.1:37723__f&onlyIfLeaderActive=true&core=tmp_collection_shard1_replica_n1&coreNodeName=core_node4&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2} status=0 QTime=101
[junit4] 2> 4361898 INFO (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:tmp_collection_shard1_replica_n3 ] o.a.s.c.RecoveryStrategy Attempting to PeerSync from [http://127.0.0.1:36849/_f/tmp_collection_shard1_replica_n1/] - recoveringAfterStartup=[true]
[junit4] 2> 4361898 WARN (recoveryExecutor-36962-thread-1-processing-n:127.0.0.1:37723__f x:tmp_collection_shard1_replica_n3 c:tmp_collection s:shard1 r:core_node4) [n:127.0.0.1:37723__f c:tmp_collection s:shard1 r:core_node4 x:
[...truncated too long message...]
HandlerTest.testPartialReplicationAfterPeerSync-seed#[CE39813856636D00]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 4456064 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationAfterPeerSync-seed#[CE39813856636D00]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPartialReplicationAfterPeerSync
[junit4] 2> 4456064 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationAfterPeerSync-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 4456268 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/aliases.json
[junit4] 2> 20 /solr/collections/source_collection/terms/shard1
[junit4] 2> 8 /solr/collections/source_collection/leaders/shard1/leader
[junit4] 2> 8 /solr/collections/tmp_collection/terms/shard1
[junit4] 2> 7 /solr/collections/tmp_collection/state.json
[junit4] 2> 6 /solr/configs/conf1
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/tmp_collection/leaders/shard1/leader
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 74 /solr/collections/source_collection/state.json
[junit4] 2> 30 /solr/clusterprops.json
[junit4] 2> 30 /solr/clusterstate.json
[junit4] 2> 24 /solr/collections/tmp_collection/state.json
[junit4] 2> 5 /solr/collections/source_collection/cdcr/state/buffer
[junit4] 2> 5 /solr/collections/source_collection/cdcr/state/process
[junit4] 2> 3 /solr/overseer_elect/election/73644973412646916-127.0.0.1:41899__f-n_0000000000
[junit4] 2> 3 /solr/collections/source_collection/leader_elect/shard1/election/73644973412646916-core_node4-n_0000000000
[junit4] 2> 2 /solr/collections/tmp_collection/cdcr/state/buffer
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2> 2 /solr/collections/tmp_collection/cdcr/state/process
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 54 /solr/overseer/queue
[junit4] 2> 40 /solr/live_nodes
[junit4] 2> 39 /solr/collections
[junit4] 2> 20 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.cdcr.CdcrReplicationHandlerTest_CE39813856636D00-001
[junit4] 2> 4456272 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationAfterPeerSync-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45481
[junit4] 2> 4456272 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationAfterPeerSync-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45481
[junit4] 2> 4456272 INFO (TEST-CdcrReplicationHandlerTest.testPartialReplicationAfterPeerSync-seed#[CE39813856636D00]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45481
[junit4] 2> Apr 25, 2020 7:31:54 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {_root_=PostingsFormat(name=MockRandom), id=Lucene84}, docValues:{_version_=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=967, maxMBSortInHeap=7.319521797817247, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@c7dd08c), locale=ar-YE, timezone=Asia/Ulan_Bator
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=33937808,total=529006592
[junit4] 2> NOTE: All tests run in this JVM: [DistributedTermsComponentTest, TestCloudInspectUtil, HttpPartitionWithTlogReplicasTest, DistributedQueryElevationComponentTest, TestObjectReleaseTracker, TestOmitPositions, MoreLikeThisComponentTest, SoftAutoCommitTest, TestConfigReload, TestFaceting, SampleTest, TestSchemaVersionResource, TestSearcherReuse, TestDistribPackageStore, SignatureUpdateProcessorFactoryTest, NodeAddedTriggerTest, TestHashPartitioner, TestDynamicFieldCollectionResource, TestExportTool, TestManagedSchema, CollectionReloadTest, DistribJoinFromCollectionTest, TestCloudJSONFacetSKG, TestSimNodeAddedTrigger, SpellCheckCollatorTest, NodeMarkersRegistrationTest, TestRebalanceLeaders, TestComplexPhraseQParserPlugin, MultiDestinationAuditLoggerTest, TestSolr4Spatial, TestFieldCacheWithThreads, LeaderTragicEventTest, TestLegacyField, DistanceFunctionTest, ChaosMonkeySafeLeaderTest, PackageManagerCLITest, TestJsonFacets, TestStressLucene, TestAuthorizationFramework, EmbeddedSolrNoSerializeTest, TestPrepRecovery, AnalysisAfterCoreReloadTest, SmileWriterTest, TestReversedWildcardFilterFactory, TestDistributedMissingSort, AdminHandlersProxyTest, TestCollectionsAPIViaSolrCloudCluster, TestSkipOverseerOperations, ScheduledTriggerIntegrationTest, BadCopyFieldTest, UpdateRequestProcessorFactoryTest, UtilsToolTest, HdfsRecoverLeaseTest, TestXmlQParser, TestJsonRequest, AnalysisErrorHandlingTest, TestBM25SimilarityFactory, TestBulkSchemaConcurrent, CloudReplicaSourceTest, TestSimExtremeIndexing, TestRandomDVFaceting, TestEmbeddedSolrServerSchemaAPI, RegexBoostProcessorTest, SortSpecParsingTest, CleanupOldIndexTest, TestTestInjection, BigEndianAscendingWordDeserializerTest, RangeFacetCloudTest, TestSegmentSorting, SolrCLIZkUtilsTest, RequestHandlersTest, AssignBackwardCompatibilityTest, TestSimScenario, ConfigSetsAPITest, TestSubQueryTransformerDistrib, FastVectorHighlighterTest, DeleteNodeTest, TestCollationField, SplitHandlerTest, AutoAddReplicasPlanActionTest, CurrencyRangeFacetCloudTest, TestSolrCloudWithHadoopAuthPlugin, CoreAdminHandlerTest, ClassificationUpdateProcessorFactoryTest, TestSolrCoreParser, TestJmxIntegration, SimpleFacetsTest, TestSolrQueryResponse, OpenExchangeRatesOrgProviderTest, DirectUpdateHandlerTest, TestLegacyTerms, TestDeleteCollectionOnDownNodes, SegmentsInfoRequestHandlerTest, ShardRoutingTest, TestFuzzyAnalyzedSuggestions, TestConfigOverlay, TestCaffeineCache, TestCloudNestedDocsSort, TestUnifiedSolrHighlighter, UninvertDocValuesMergePolicyTest, TestTrackingShardHandlerFactory, TestQueryingOnDownCollection, TestNumericTerms64, MissingSegmentRecoveryTest, TestApiFramework, CoreMergeIndexesAdminHandlerTest, DistributedFacetPivotSmallAdvancedTest, TestReplicationHandler, TestRandomCollapseQParserPlugin, MergeStrategyTest, AtomicUpdateRemovalJavabinTest, ResponseHeaderTest, MoveReplicaTest, TestExactSharedStatsCache, TestExceedMaxTermLength, VersionInfoTest, TermVectorComponentTest, TestSnapshotCloudManager, TestWaitForStateWithJettyShutdowns, TestTextField, TestGroupingSearch, HighlighterTest, TestSchemaManager, DeleteStatusTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestMultiWordSynonyms, TriggerSetPropertiesIntegrationTest, HDFSCollectionsAPITest, TestCloudSearcherWarming, TestSolrQueryParser, TestManagedStopFilterFactory, TestPayloadCheckQParserPlugin, TestRealTimeGet, URLClassifyProcessorTest, TestReqParamsAPI, ExecutePlanActionTest, TemplateUpdateProcessorTest, TestCoreAdminApis, TestTolerantUpdateProcessorCloud, ActionThrottleTest, OverseerRolesTest, DistanceUnitsTest, CdcrRequestHandlerTest, TestSha256AuthenticationProvider, RecoveryZkTest, TestCloudPseudoReturnFields, CollectionsAPIDistributedZkTest, CursorPagingTest, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestSolrCoreProperties, TestSolrTestCaseJ4, TestTolerantSearch, TestTrie, PathHierarchyTokenizerFactoryTest, TestDeprecatedFilters, TestLuceneIndexBackCompat, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ConcurrentCreateRoutedAliasTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DocValuesNotIndexedTest, ForceLeaderTest, ForceLeaderWithTlogReplicasTest, HttpPartitionOnCommitTest, LeaderElectionTest, LeaderFailoverAfterPartitionTest, MetricsHistoryIntegrationTest, MetricsHistoryWithAuthIntegrationTest, MigrateRouteKeyTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, RestartWhileUpdatingTest, RoutingToNodesWithPropertiesTest, SSLMigrationTest, SaslZkACLProviderTest, ShardRoutingCustomTest, SplitShardTest, SyncSliceTest, SystemCollectionCompatTest, TestAuthenticationFramework, TestCloudConsistency, TestCloudDeleteByQuery, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDownShardTolerantSearch, TestDynamicFieldNamesIndexCorrectly, TestExclusionRuleCollectionAccess, TestLockTree, TestMiniSolrCloudClusterSSL, TestOnReconnectListenerSupport, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TestStressInPlaceUpdates, TestTlogReplayVsRecovery, TestTlogReplica, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, VMParamsZkACLAndCredentialsProvidersTest, ZkCLITest, ZkNodePropsTest, ZkShardTermsTest, AssignTest, AsyncCallRequestStatusResponseTest, CustomCollectionTest, ShardSplitTest, TriggerCooldownIntegrationTest, TestSimDistribStateManager, TestSimDistributedQueue, TestSimExecutePlanAction, CdcrBootstrapTest, CdcrReplicationHandlerTest]
[junit4] Completed [574/906 (1!)] on J0 in 100.85s, 5 tests, 1 failure <<< FAILURES!
[...truncated 53701 lines...]