Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1365/
3 tests failed. FAILED: org.apache.lucene.index.TestIndexSorting.testRandom3 Error Message: Java heap space Stack Trace: java.lang.OutOfMemoryError: Java heap space at __randomizedtesting.SeedInfo.seed([153A273D1319441C:B7E269E777EB6D1A]:0) at org.apache.lucene.util.packed.Packed64.<init>(Packed64.java:73) at org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:972) at org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:939) at org.apache.lucene.util.packed.GrowableWriter.ensureCapacity(GrowableWriter.java:80) at org.apache.lucene.util.packed.GrowableWriter.set(GrowableWriter.java:88) at org.apache.lucene.util.packed.AbstractPagedMutable.set(AbstractPagedMutable.java:98) at org.apache.lucene.util.fst.NodeHash.addNew(NodeHash.java:152) at org.apache.lucene.util.fst.NodeHash.rehash(NodeHash.java:169) at org.apache.lucene.util.fst.NodeHash.add(NodeHash.java:133) at org.apache.lucene.util.fst.Builder.compileNode(Builder.java:200) at org.apache.lucene.util.fst.Builder.freezeTail(Builder.java:296) at org.apache.lucene.util.fst.Builder.add(Builder.java:400) at org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.writeFST(MemoryDocValuesConsumer.java:373) at org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.addSortedField(MemoryDocValuesConsumer.java:416) at org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.addSortedField(MemoryDocValuesConsumer.java:406) at org.apache.lucene.codecs.DocValuesConsumer.mergeSortedField(DocValuesConsumer.java:527) at org.apache.lucene.codecs.DocValuesConsumer.merge(DocValuesConsumer.java:139) at org.apache.lucene.codecs.perfield.PerFieldDocValuesFormat$FieldsWriter.merge(PerFieldDocValuesFormat.java:151) at org.apache.lucene.index.SegmentMerger.mergeDocValues(SegmentMerger.java:181) at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:125) at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4390) at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4030) at org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40) at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2233) at org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5048) at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1718) at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1460) at org.apache.lucene.index.TestIndexSorting.testRandom3(TestIndexSorting.java:2301) 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) FAILED: org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads Error Message: Exception during query Stack Trace: java.lang.RuntimeException: Exception during query at __randomizedtesting.SeedInfo.seed([DCE716222F6A0505:F0D561AFA3AE7381]:0) at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886) at org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] xml response was: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> </response> request was:q=int_i:17&wt=xml at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879) ... 40 more FAILED: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test Error Message: Timeout waiting for all live and active Stack Trace: java.lang.AssertionError: Timeout waiting for all live and active at __randomizedtesting.SeedInfo.seed([DCE716222F6A0505:54B329F8819668FD]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.testBasics(SharedFSAutoReplicaFailoverTest.java:246) at org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest.test(SharedFSAutoReplicaFailoverTest.java:136) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 1230 lines...] [junit4] Suite: org.apache.lucene.index.TestIndexSorting [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=TestIndexSorting -Dtests.method=testRandom3 -Dtests.seed=153A273D1319441C -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=en-MT -Dtests.timezone=Pacific/Pohnpei -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 146s J1 | TestIndexSorting.testRandom3 <<< [junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space [junit4] > at __randomizedtesting.SeedInfo.seed([153A273D1319441C:B7E269E777EB6D1A]:0) [junit4] > at org.apache.lucene.util.packed.Packed64.<init>(Packed64.java:73) [junit4] > at org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:972) [junit4] > at org.apache.lucene.util.packed.PackedInts.getMutable(PackedInts.java:939) [junit4] > at org.apache.lucene.util.packed.GrowableWriter.ensureCapacity(GrowableWriter.java:80) [junit4] > at org.apache.lucene.util.packed.GrowableWriter.set(GrowableWriter.java:88) [junit4] > at org.apache.lucene.util.packed.AbstractPagedMutable.set(AbstractPagedMutable.java:98) [junit4] > at org.apache.lucene.util.fst.NodeHash.addNew(NodeHash.java:152) [junit4] > at org.apache.lucene.util.fst.NodeHash.rehash(NodeHash.java:169) [junit4] > at org.apache.lucene.util.fst.NodeHash.add(NodeHash.java:133) [junit4] > at org.apache.lucene.util.fst.Builder.compileNode(Builder.java:200) [junit4] > at org.apache.lucene.util.fst.Builder.freezeTail(Builder.java:296) [junit4] > at org.apache.lucene.util.fst.Builder.add(Builder.java:400) [junit4] > at org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.writeFST(MemoryDocValuesConsumer.java:373) [junit4] > at org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.addSortedField(MemoryDocValuesConsumer.java:416) [junit4] > at org.apache.lucene.codecs.memory.MemoryDocValuesConsumer.addSortedField(MemoryDocValuesConsumer.java:406) [junit4] > at org.apache.lucene.codecs.DocValuesConsumer.mergeSortedField(DocValuesConsumer.java:527) [junit4] > at org.apache.lucene.codecs.DocValuesConsumer.merge(DocValuesConsumer.java:139) [junit4] > at org.apache.lucene.codecs.perfield.PerFieldDocValuesFormat$FieldsWriter.merge(PerFieldDocValuesFormat.java:151) [junit4] > at org.apache.lucene.index.SegmentMerger.mergeDocValues(SegmentMerger.java:181) [junit4] > at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:125) [junit4] > at org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4390) [junit4] > at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:4030) [junit4] > at org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40) [junit4] > at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2233) [junit4] > at org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5048) [junit4] > at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1718) [junit4] > at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1460) [junit4] > at org.apache.lucene.index.TestIndexSorting.testRandom3(TestIndexSorting.java:2301) [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/core/test/J1/temp/lucene.index.TestIndexSorting_153A273D1319441C-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {docs=BlockTreeOrds(blocksize=128), sparse_text=PostingsFormat(name=Memory), norms=BlockTreeOrds(blocksize=128), positions=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=BlockTreeOrds(blocksize=128), term_vectors=BlockTreeOrds(blocksize=128)}, docValues:{multi_valued_long=DocValuesFormat(name=Lucene70), foo=DocValuesFormat(name=Lucene70), numeric=DocValuesFormat(name=Memory), float=DocValuesFormat(name=Direct), multi_valued_numeric=DocValuesFormat(name=Memory), long=DocValuesFormat(name=Direct), points=DocValuesFormat(name=Asserting), sparse_int=DocValuesFormat(name=Direct), bar=DocValuesFormat(name=Memory), multi_valued_double=DocValuesFormat(name=Memory), docs=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Memory), multi_valued_int=DocValuesFormat(name=Asserting), multi_valued_bytes=DocValuesFormat(name=Memory), sparse_text=DocValuesFormat(name=Direct), double=DocValuesFormat(name=Memory), positions=DocValuesFormat(name=Lucene70), sparse_binary=DocValuesFormat(name=Direct), int=DocValuesFormat(name=Memory), sorted=DocValuesFormat(name=Memory), multi_valued_string=DocValuesFormat(name=Memory), norms=DocValuesFormat(name=Memory), sparse=DocValuesFormat(name=Direct), bytes=DocValuesFormat(name=Memory), binary=DocValuesFormat(name=Memory), dense_int=DocValuesFormat(name=Memory), multi_valued_float=DocValuesFormat(name=Lucene70), term_vectors=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1339, maxMBSortInHeap=7.94065038672138, sim=RandomSimilarity(queryNorm=true): {positions=BM25(k1=1.2,b=0.75), id=DFR GB2, term_vectors=IB SPL-D2}, locale=en-MT, timezone=Pacific/Pohnpei [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=273250320,total=436207616 [junit4] 2> NOTE: All tests run in this JVM: [TestIntroSorter, TestBlendedTermQuery, TestFastDecompressionMode, TestCodecUtil, TestFilterDirectoryReader, TestSortedSetSelector, TestPointQueries, TestPrefixCodedTerms, TestSumDocFreq, TestMultiTermConstantScore, TestDeterminism, TestTermQuery, LimitedFiniteStringsIteratorTest, TestRollback, TestPositiveScoresOnlyCollector, TestFieldType, TestPrefixInBooleanQuery, TestLockFactory, TestAtomicUpdate, TestCharArraySet, TestIndexOrDocValuesQuery, TestPointValues, TestIndexWriterOutOfFileDescriptors, Test2BDocs, TestSortRandom, TestFileSwitchDirectory, TestDocInverterPerFieldErrorInfo, TestMixedDocValuesUpdates, Test2BPostings, TestSearcherManager, TestReaderClosed, TestBooleanQuery, TestSparseFixedBitSet, TestNorms, TestBM25Similarity, TestReusableStringReader, TestAddIndexes, TestDocValuesQueries, TestTermdocPerf, TestPrefixRandom, TestConcurrentMergeScheduler, TestOmitTf, TestFSTs, TestMultiPhraseEnum, TestScoreCachingWrappingScorer, TestSimpleExplanationsWithFillerDocs, TestDuelingCodecsAtNight, TestBytesRefArray, TestRegExp, TestIndexFileDeleter, TestMmapDirectory, TestBytesRefAttImpl, TestFilterLeafReader, TestBlockPostingsFormat3, TestPhraseQuery, TestParallelLeafReader, TestDoubleValuesSource, TestVirtualMethod, TestDoc, TestSpanOrQuery, TestFieldInvertState, TestFieldValueQuery, TestCustomSearcherSort, TestBufferedChecksum, Test2BSortedDocValuesFixedSorted, TestRAMDirectory, TestSameScoresWithThreads, TestMatchNoDocsQuery, TestTransactions, TestLRUQueryCache, TestIndexSorting] [junit4] Completed [295/454 (1!)] on J1 in 182.85s, 50 tests, 1 error <<< FAILURES! [...truncated 530 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/core/test/temp/junit4-J1-20170728_043121_1577079894741960343426.sysout [junit4] >>> JVM J1 emitted unexpected output (verbatim) ---- [junit4] java.lang.OutOfMemoryError: Java heap space [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid12707.hprof ... [junit4] Heap dump file created [497393830 bytes in 2.051 secs] [junit4] <<< JVM J1: EOF ---- [...truncated 10689 lines...] [junit4] Suite: org.apache.solr.cloud.SharedFSAutoReplicaFailoverTest [junit4] 2> 1814601 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/init-core-data-001 [junit4] 2> 1814602 WARN (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 1814604 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 1814606 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 1814606 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /xv_uud/ [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 1814651 WARN (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 1814656 WARN (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1814658 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1814672 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_36034_hdfs____3xfapk/webapp [junit4] 2> 1815048 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:36034 [junit4] 2> 1815128 WARN (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1815129 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1815144 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_38676_datanode____.eu3x1l/webapp [junit4] 2> 1815864 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38676 [junit4] 2> 1815934 WARN (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1815935 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1815952 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_34938_datanode____.ol7j98/webapp [junit4] 2> 1816098 INFO (IPC Server handler 3 on 59077) [ ] BlockStateChange BLOCK* processReport: from storage DS-617cbb16-2e7a-4317-92eb-4be5b5e09675 node DatanodeRegistration(127.0.0.1:41372, datanodeUuid=afe5a977-95c2-4f56-a6be-6985af0727ac, infoPort=36168, infoSecurePort=0, ipcPort=45814, storageInfo=lv=-56;cid=testClusterID;nsid=1091277997;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 1816098 INFO (IPC Server handler 3 on 59077) [ ] BlockStateChange BLOCK* processReport: from storage DS-beb4c5e9-14af-42f0-a5f9-bb56f2e8ac32 node DatanodeRegistration(127.0.0.1:41372, datanodeUuid=afe5a977-95c2-4f56-a6be-6985af0727ac, infoPort=36168, infoSecurePort=0, ipcPort=45814, storageInfo=lv=-56;cid=testClusterID;nsid=1091277997;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 1816620 INFO (SUITE-SharedFSAutoReplicaFailoverTest-seed#[DCE716222F6A0505]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34938 [junit4] 2> 1816891 INFO (IPC Server handler 7 on 59077) [ ] BlockStateChange BLOCK* processReport: from storage DS-a3f2a88c-7c78-4dc3-b8d1-1dfe81ec0d57 node DatanodeRegistration(127.0.0.1:45700, datanodeUuid=2c1d098f-0ee2-492d-b2b5-04b873775b41, infoPort=36528, infoSecurePort=0, ipcPort=49543, storageInfo=lv=-56;cid=testClusterID;nsid=1091277997;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 1816891 INFO (IPC Server handler 7 on 59077) [ ] BlockStateChange BLOCK* processReport: from storage DS-89b61e63-d257-472e-bf87-6c29c6b0bd33 node DatanodeRegistration(127.0.0.1:45700, datanodeUuid=2c1d098f-0ee2-492d-b2b5-04b873775b41, infoPort=36528, infoSecurePort=0, ipcPort=49543, storageInfo=lv=-56;cid=testClusterID;nsid=1091277997;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 1817131 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1817132 INFO (Thread-12809) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1817132 INFO (Thread-12809) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1817136 ERROR (Thread-12809) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1817232 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.ZkTestServer start zk server on port:52619 [junit4] 2> 1817256 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1817258 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1817260 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1817262 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1817264 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1817265 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1817267 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1817268 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1817270 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1817272 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1817273 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1817275 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise [junit4] 2> 1817456 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1817458 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c8fbc23{/xv_uud,null,AVAILABLE} [junit4] 2> 1817458 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b69b2be{HTTP/1.1,[http/1.1]}{127.0.0.1:46862} [junit4] 2> 1817458 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.Server Started @1820398ms [junit4] 2> 1817461 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/xv_uud, hostPort=46862, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/control-001/cores} [junit4] 2> 1817462 ERROR (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1817462 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1817462 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1817462 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1817462 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-28T08:18:09.221Z [junit4] 2> 1817466 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1817466 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/control-001/solr.xml [junit4] 2> 1817472 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1817476 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52619/solr [junit4] 2> 1817588 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1817589 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46862_xv_uud [junit4] 2> 1817590 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.Overseer Overseer (id=98384601998426116-127.0.0.1:46862_xv_uud-n_0000000000) starting [junit4] 2> 1817601 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46862_xv_uud [junit4] 2> 1817602 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1817735 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1817741 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1817742 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1817743 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/control-001/cores [junit4] 2> 1817767 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1817768 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52619/solr ready [junit4] 2> 1817769 INFO (qtp427977620-21396) [n:127.0.0.1:46862_xv_uud ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46862_xv_uud&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1817771 INFO (OverseerThreadFactory-4103-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.CreateCollectionCmd Create collection control_collection [junit4] 2> 1817883 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1817883 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1817989 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1818903 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1818919 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test [junit4] 2> 1819014 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1819033 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true [junit4] 2> 1819034 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1819034 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:59077/solr_hdfs_home [junit4] 2> 1819034 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 1819034 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1819034 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[hdfs://localhost:59077/solr_hdfs_home/control_collection/core_node2/data/] [junit4] 2> 1819037 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/control_collection/core_node2/data/snapshot_metadata [junit4] 2> 1819046 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1819046 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [4194304] will allocate [1] slabs and use ~[4194304] bytes [junit4] 2> 1819048 WARN (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 1824482 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1824484 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/control_collection/core_node2/data [junit4] 2> 1824514 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/control_collection/core_node2/data/index [junit4] 2> 1824527 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1824527 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [4194304] will allocate [1] slabs and use ~[4194304] bytes [junit4] 2> 1824539 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1824540 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=229929491, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1824586 INFO (IPC Server handler 9 on 59077) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41372 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-a3f2a88c-7c78-4dc3-b8d1-1dfe81ec0d57:NORMAL:127.0.0.1:45700|RBW], ReplicaUC[[DISK]DS-beb4c5e9-14af-42f0-a5f9-bb56f2e8ac32:NORMAL:127.0.0.1:41372|RBW]]} size 0 [junit4] 2> 1824587 INFO (IPC Server handler 6 on 59077) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:45700 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-a3f2a88c-7c78-4dc3-b8d1-1dfe81ec0d57:NORMAL:127.0.0.1:45700|RBW], ReplicaUC[[DISK]DS-beb4c5e9-14af-42f0-a5f9-bb56f2e8ac32:NORMAL:127.0.0.1:41372|RBW]]} size 0 [junit4] 2> 1824598 WARN (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1824662 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 1824662 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1824662 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 1824682 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1824682 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1824685 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=52.01171875, floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=1.7159922672271488, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1824691 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2305725b[control_collection_shard1_replica_n1] main] [junit4] 2> 1824693 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1824694 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1824695 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1824696 INFO (searcherExecutor-4106-thread-1-processing-n:127.0.0.1:46862_xv_uud x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@2305725b[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1824697 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574153639906246656 [junit4] 2> 1824704 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1824704 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1824704 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46862/xv_uud/control_collection_shard1_replica_n1/ [junit4] 2> 1824704 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1824705 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:46862/xv_uud/control_collection_shard1_replica_n1/ has no replicas [junit4] 2> 1824705 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1824707 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1824711 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46862/xv_uud/control_collection_shard1_replica_n1/ shard1 [junit4] 2> 1824814 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1824863 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1824864 INFO (qtp427977620-21398) [n:127.0.0.1:46862_xv_uud c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=6981 [junit4] 2> 1824870 INFO (qtp427977620-21396) [n:127.0.0.1:46862_xv_uud ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 1824970 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 1825778 INFO (OverseerCollectionConfigSetProcessor-98384601998426116-127.0.0.1:46862_xv_uud-n_0000000000) [n:127.0.0.1:46862_xv_uud ] 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> 1825870 INFO (qtp427977620-21396) [n:127.0.0.1:46862_xv_uud ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:46862_xv_uud&wt=javabin&version=2} status=0 QTime=8101 [junit4] 2> 1825877 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1825878 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52619/solr ready [junit4] 2> 1825878 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1825879 INFO (qtp427977620-21394) [n:127.0.0.1:46862_xv_uud ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1825881 INFO (OverseerThreadFactory-4103-thread-2-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 1825882 WARN (OverseerThreadFactory-4103-thread-2-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores. [junit4] 2> 1826090 INFO (qtp427977620-21394) [n:127.0.0.1:46862_xv_uud ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 1826090 INFO (qtp427977620-21394) [n:127.0.0.1:46862_xv_uud ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=210 [junit4] 2> 1826273 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-1-001 of type NRT [junit4] 2> 1826274 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1826276 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@633a55c1{/xv_uud,null,AVAILABLE} [junit4] 2> 1826276 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45b5cd26{HTTP/1.1,[http/1.1]}{127.0.0.1:38872} [junit4] 2> 1826279 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.Server Started @1829219ms [junit4] 2> 1826280 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostContext=/xv_uud, hostPort=38872, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-1-001/cores} [junit4] 2> 1826280 ERROR (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1826280 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1826281 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1826281 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1826281 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-28T08:18:18.040Z [junit4] 2> 1826297 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1826297 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-1-001/solr.xml [junit4] 2> 1826303 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1826307 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52619/solr [junit4] 2> 1826321 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1826324 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1826326 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38872_xv_uud [junit4] 2> 1826328 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1826328 INFO (zkCallback-1497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1826334 INFO (zkCallback-1502-thread-1-processing-n:127.0.0.1:38872_xv_uud) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1826487 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1826494 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1826495 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1826496 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-1-001/cores [junit4] 2> 1826531 INFO (qtp2076066684-21448) [n:127.0.0.1:38872_xv_uud ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:38872_xv_uud&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1826534 INFO (OverseerThreadFactory-4103-thread-3-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:38872_xv_uud for creating new replica [junit4] 2> 1826534 INFO (OverseerCollectionConfigSetProcessor-98384601998426116-127.0.0.1:46862_xv_uud-n_0000000000) [n:127.0.0.1:46862_xv_uud ] 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> 1826537 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1826537 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1827558 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1827588 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=test [junit4] 2> 1827673 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1827694 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using configuration from collection collection1, trusted=true [junit4] 2> 1827695 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n1' (registry 'solr.core.collection1.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1827695 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:59077/solr_hdfs_home [junit4] 2> 1827695 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 1827695 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1827695 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-1-001/cores/collection1_shard2_replica_n1], dataDir=[hdfs://localhost:59077/solr_hdfs_home/collection1/core_node2/data/] [junit4] 2> 1827697 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/collection1/core_node2/data/snapshot_metadata [junit4] 2> 1827706 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1827706 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [4194304] will allocate [1] slabs and use ~[4194304] bytes [junit4] 2> 1827710 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1827711 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/collection1/core_node2/data [junit4] 2> 1827730 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/collection1/core_node2/data/index [junit4] 2> 1827738 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1827738 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [4194304] will allocate [1] slabs and use ~[4194304] bytes [junit4] 2> 1827741 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1827744 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=229929491, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1827768 INFO (IPC Server handler 6 on 59077) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:45700 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-617cbb16-2e7a-4317-92eb-4be5b5e09675:NORMAL:127.0.0.1:41372|RBW], ReplicaUC[[DISK]DS-89b61e63-d257-472e-bf87-6c29c6b0bd33:NORMAL:127.0.0.1:45700|RBW]]} size 0 [junit4] 2> 1827769 INFO (IPC Server handler 0 on 59077) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41372 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-617cbb16-2e7a-4317-92eb-4be5b5e09675:NORMAL:127.0.0.1:41372|RBW], ReplicaUC[[DISK]DS-89b61e63-d257-472e-bf87-6c29c6b0bd33:NORMAL:127.0.0.1:45700|RBW]]} size 0 [junit4] 2> 1827776 WARN (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1827854 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 1827854 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1827854 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 1827867 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1827867 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1827869 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=52.01171875, floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=1.7159922672271488, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1827875 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12a3637b[collection1_shard2_replica_n1] main] [junit4] 2> 1827876 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1827877 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1827878 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1827880 INFO (searcherExecutor-4117-thread-1-processing-n:127.0.0.1:38872_xv_uud x:collection1_shard2_replica_n1 s:shard2 c:collection1) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@12a3637b[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1827880 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574153643243864064 [junit4] 2> 1827888 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1827888 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1827888 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:38872/xv_uud/collection1_shard2_replica_n1/ [junit4] 2> 1827888 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1827888 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:38872/xv_uud/collection1_shard2_replica_n1/ has no replicas [junit4] 2> 1827888 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 1827892 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38872/xv_uud/collection1_shard2_replica_n1/ shard2 [junit4] 2> 1828043 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1828045 INFO (qtp2076066684-21450) [n:127.0.0.1:38872_xv_uud c:collection1 s:shard2 x:collection1_shard2_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1508 [junit4] 2> 1828048 INFO (qtp2076066684-21448) [n:127.0.0.1:38872_xv_uud ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:38872_xv_uud&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1516 [junit4] 2> 1828207 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-2-001 of type NRT [junit4] 2> 1828208 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1828211 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e0d0d9f{/xv_uud,null,AVAILABLE} [junit4] 2> 1828212 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@21cd0e3b{HTTP/1.1,[http/1.1]}{127.0.0.1:45892} [junit4] 2> 1828212 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.e.j.s.Server Started @1831152ms [junit4] 2> 1828212 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostContext=/xv_uud, hostPort=45892, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-2-001/cores} [junit4] 2> 1828212 ERROR (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1828215 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 1828215 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1828215 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 1828215 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-28T08:18:19.974Z [junit4] 2> 1828221 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1828221 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-2-001/solr.xml [junit4] 2> 1828227 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 1828231 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52619/solr [junit4] 2> 1828242 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1828245 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1828248 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45892_xv_uud [junit4] 2> 1828249 INFO (zkCallback-1490-thread-1-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1828258 INFO (zkCallback-1497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1828258 INFO (zkCallback-1502-thread-1-processing-n:127.0.0.1:38872_xv_uud) [n:127.0.0.1:38872_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1828261 INFO (zkCallback-1508-thread-1-processing-n:127.0.0.1:45892_xv_uud) [n:127.0.0.1:45892_xv_uud ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1828363 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1828371 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1828371 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1828373 INFO (TEST-SharedFSAutoReplicaFailoverTest.test-seed#[DCE716222F6A0505]) [n:127.0.0.1:45892_xv_uud ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-2-001/cores [junit4] 2> 1828412 INFO (qtp2076066684-21449) [n:127.0.0.1:38872_xv_uud ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:45892_xv_uud&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1828415 INFO (OverseerThreadFactory-4103-thread-4-processing-n:127.0.0.1:46862_xv_uud) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:45892_xv_uud for creating new replica [junit4] 2> 1828415 INFO (OverseerCollectionConfigSetProcessor-98384601998426116-127.0.0.1:46862_xv_uud-n_0000000000) [n:127.0.0.1:46862_xv_uud ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1828418 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n3&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1828418 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 1829438 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 1829454 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.IndexSchema [collection1_shard1_replica_n3] Schema name=test [junit4] 2> 1829555 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1829575 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n3' using configuration from collection collection1, trusted=true [junit4] 2> 1829575 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n3' (registry 'solr.core.collection1.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1be80524 [junit4] 2> 1829575 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:59077/solr_hdfs_home [junit4] 2> 1829576 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 1829576 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 1829576 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.SolrCore [[collection1_shard1_replica_n3] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.SharedFSAutoReplicaFailoverTest_DCE716222F6A0505-001/shard-2-001/cores/collection1_shard1_replica_n3], dataDir=[hdfs://localhost:59077/solr_hdfs_home/collection1/core_node4/data/] [junit4] 2> 1829577 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/collection1/core_node4/data/snapshot_metadata [junit4] 2> 1829587 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1829587 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [4194304] will allocate [1] slabs and use ~[4194304] bytes [junit4] 2> 1829590 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1829591 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/collection1/core_node4/data [junit4] 2> 1829610 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:59077/solr_hdfs_home/collection1/core_node4/data/index [junit4] 2> 1829617 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1829617 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [4194304] will allocate [1] slabs and use ~[4194304] bytes [junit4] 2> 1829620 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1829621 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=229929491, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 1829634 INFO (IPC Server handler 1 on 59077) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:45700 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-617cbb16-2e7a-4317-92eb-4be5b5e09675:NORMAL:127.0.0.1:41372|RBW], ReplicaUC[[DISK]DS-a3f2a88c-7c78-4dc3-b8d1-1dfe81ec0d57:NORMAL:127.0.0.1:45700|FINALIZED]]} size 0 [junit4] 2> 1829635 INFO (IPC Server handler 2 on 59077) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41372 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-a3f2a88c-7c78-4dc3-b8d1-1dfe81ec0d57:NORMAL:127.0.0.1:45700|FINALIZED], ReplicaUC[[DISK]DS-beb4c5e9-14af-42f0-a5f9-bb56f2e8ac32:NORMAL:127.0.0.1:41372|FINALIZED]]} size 0 [junit4] 2> 1829644 WARN (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1829707 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 1829707 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1829707 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 1829720 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1829720 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1829722 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=52.01171875, floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=1.7159922672271488, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 1829727 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a95726c[collection1_shard1_replica_n3] main] [junit4] 2> 1829728 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1829729 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 1829730 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1829732 INFO (searcherExecutor-4128-thread-1-processing-n:127.0.0.1:45892_xv_uud x:collection1_shard1_replica_n3 s:shard1 c:collection1) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.SolrCore [collection1_shard1_replica_n3] Registered new searcher Searcher@5a95726c[collection1_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1829732 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1574153645185826816 [junit4] 2> 1829738 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1829738 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 x:collection1_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1829738 INFO (qtp383665616-21485) [n:127.0.0.1:45892_xv_uud c:collection1 s:shard1 [...truncated too long message...] ine-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AtomicUpdateProcessorFactoryTest -Dtests.method=testMultipleThreads -Dtests.seed=DCE716222F6A0505 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=pl -Dtests.timezone=W-SU -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.05s J1 | AtomicUpdateProcessorFactoryTest.testMultipleThreads <<< [junit4] > Throwable #1: java.lang.RuntimeException: Exception during query [junit4] > at __randomizedtesting.SeedInfo.seed([DCE716222F6A0505:F0D561AFA3AE7381]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:886) [junit4] > at org.apache.solr.update.processor.AtomicUpdateProcessorFactoryTest.testMultipleThreads(AtomicUpdateProcessorFactoryTest.java:264) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1] [junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?> [junit4] > <response> [junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result> [junit4] > </response> [junit4] > request was:q=int_i:17&wt=xml [junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:879) [junit4] > ... 40 more [junit4] 2> 4300367 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 4300367 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1321363586 [junit4] 2> 4300367 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 4300368 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 4300370 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 4300370 INFO (SUITE-AtomicUpdateProcessorFactoryTest-seed#[DCE716222F6A0505]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 4300370 INFO (coreCloseExecutor-8549-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@41cefa1a [junit4] 2> 4300373 INFO (coreCloseExecutor-8549-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1104083482 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.update.processor.AtomicUpdateProcessorFactoryTest_DCE716222F6A0505-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name_s=FSTOrd50, title_stringNoNorms=PostingsFormat(name=LuceneFixedGap), title_stemmed=BlockTreeOrds(blocksize=128), multiDefault=PostingsFormat(name=LuceneFixedGap), cat=PostingsFormat(name=LuceneFixedGap), id=FSTOrd50, text=Lucene50(blocksize=128), title=BlockTreeOrds(blocksize=128), title_lettertok=FSTOrd50}, docValues:{int_i=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Lucene70), id_i1=DocValuesFormat(name=Lucene70), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Lucene70), count_i=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Asserting), range_facet_l=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1542, maxMBSortInHeap=6.1735095805599105, sim=RandomSimilarity(queryNorm=true): {}, locale=pl, timezone=W-SU [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=4,threads=1,free=174853400,total=532152320 [junit4] 2> NOTE: All tests run in this JVM: [TestSQLHandlerNonCloud, FileUtilsTest, OverseerStatusTest, HLLSerializationTest, CollectionTooManyReplicasTest, TestImpersonationWithHadoopAuth, TestDocBasedVersionConstraints, CSVRequestHandlerTest, TestElisionMultitermQuery, TestCollationFieldDocValues, ConcurrentDeleteAndCreateCollectionTest, TestManagedResourceStorage, TestConfigSets, CursorMarkTest, PKIAuthenticationIntegrationTest, SecurityConfHandlerTest, TestSolrCoreParser, SSLMigrationTest, ChaosMonkeySafeLeaderTest, TestDynamicLoading, TestBinaryResponseWriter, TestGraphTermsQParserPlugin, TestEmbeddedSolrServerSchemaAPI, TestPostingsSolrHighlighter, ShardRoutingCustomTest, TestStressRecovery, ZkStateReaderTest, ResourceLoaderTest, TestLegacyNumericRangeQueryBuilder, ReplicationFactorTest, TestSystemCollAutoCreate, DistributedQueryComponentCustomSortTest, TestManagedSchemaAPI, TestFiltering, TestStressCloudBlindAtomicUpdates, DistributedIntervalFacetingTest, ChangedSchemaMergeTest, TestNumericRangeQuery32, TestXIncludeConfig, TestUniqueKeyFieldResource, TestRTGBase, SOLR749Test, TestHttpShardHandlerFactory, TestSolrCLIRunExample, TestBM25SimilarityFactory, TlogReplayBufferedWhileIndexingTest, HdfsThreadLeakTest, TestFastLRUCache, TestSubQueryTransformerCrossCore, TermVectorComponentTest, CreateCollectionCleanupTest, TestSystemIdResolver, ShardRoutingTest, BasicFunctionalityTest, ZkSolrClientTest, RuleEngineTest, TestFreeTextSuggestions, LukeRequestHandlerTest, TestDistribDocBasedVersion, BJQParserTest, HdfsWriteToMultipleCollectionsTest, HdfsCollectionsAPIDistributedZkTest, ActionThrottleTest, SpatialFilterTest, TestRandomRequestDistribution, TestFieldCacheReopen, DistanceFunctionTest, CheckHdfsIndexTest, AnalysisAfterCoreReloadTest, TestCharFilters, TestSolrIndexConfig, DeleteLastCustomShardedReplicaTest, AssignTest, TestCopyFieldCollectionResource, TestRandomCollapseQParserPlugin, DistributedFacetPivotSmallAdvancedTest, SolrJmxReporterTest, TestUseDocValuesAsStored2, TestPerFieldSimilarity, TestInitParams, TestConfigOverlay, ZkControllerTest, IndexSchemaTest, BitVectorTest, DOMUtilTest, TestRecoveryHdfs, StatelessScriptUpdateProcessorFactoryTest, JsonLoaderTest, TestPartialUpdateDeduplication, TestHashPartitioner, CdcrBootstrapTest, TestGeoJSONResponseWriter, DistributedMLTComponentTest, TestSweetSpotSimilarityFactory, TestDistribIDF, TestManagedSynonymGraphFilterFactory, MoreLikeThisHandlerTest, HdfsLockFactoryTest, DataDrivenBlockJoinTest, TestConfigReload, CollectionsAPIDistributedZkTest, TestSlowCompositeReaderWrapper, InfoHandlerTest, TestXmlQParserPlugin, TestLegacyNumericUtils, DirectSolrSpellCheckerTest, TestSimpleQParserPlugin, TestShardHandlerFactory, SolrCmdDistributorTest, TestPerFieldSimilarityWithDefaultOverride, SharedFSAutoReplicaFailoverTest, CacheHeaderTest, AlternateDirectoryTest, NoCacheHeaderTest, MigrateRouteKeyTest, TestSolrXml, AutoScalingHandlerTest, BadComponentTest, TestJsonRequest, TestBulkSchemaConcurrent, RankQueryTest, ZkCLITest, TestDistributedSearch, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteShardTest, DocValuesNotIndexedTest, ForceLeaderTest, LeaderInitiatedRecoveryOnCommitTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, PeerSyncReplicationTest, RecoveryAfterSoftCommitTest, SaslZkACLProviderTest, SimpleCollectionCreateDeleteTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, TestAuthenticationFramework, TestCloudInspectUtil, TestCloudJSONFacetJoinDomain, TestCloudRecovery, TestClusterProperties, TestCollectionsAPIViaSolrCloudCluster, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterSSL, TestPullReplica, TestRandomFlRTGCloud, TestSizeLimitedDistributedMap, TestTlogReplica, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsSyncSliceTest, HdfsTlogReplayBufferedWhileIndexingTest, TestManagedStopFilterFactory, PreAnalyzedFieldManagedSchemaCloudTest, TestPointFields, TestMultiWordSynonyms, TestNoOpRegenerator, TestOverriddenPrefixQueryForCustomFieldType, TestPayloadCheckQParserPlugin, TestPayloadScoreQParserPlugin, TestQueryWrapperFilter, TestReRankQParserPlugin, TestStressUserVersions, TestTrieFacet, TestMinMaxOnMultiValuedField, BlockJoinFacetDistribTest, BlockJoinFacetRandomTest, BlockJoinFacetSimpleTest, TestNestedDocsSort, TestScoreJoinQPScore, TestLRUStatsCache, TestSha256AuthenticationProvider, ResponseHeaderTest, ConjunctionSolrSpellCheckerTest, TestAnalyzeInfixSuggestions, TestFileDictionaryLookup, TestHighFrequencyDictionaryFactory, BlockCacheTest, BlockDirectoryTest, TestFieldCacheVsDocValues, VersionInfoTest, AtomicUpdateProcessorFactoryTest] [junit4] Completed [704/726 (2!)] on J1 in 0.70s, 4 tests, 1 error <<< FAILURES! [...truncated 7589 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:818: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:762: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created: * java_pid12707.hprof Total time: 277 minutes 57 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org