Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20871/
Java: 64bit/jdk-10-ea+29 -XX:+UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  
org.apache.lucene.search.similarities.TestBasicModelIn.testRandomScoring

Error Message:
score(0.99999994)=2.8E-44 > score(1.0)=2.7E-44

Stack Trace:
java.lang.AssertionError: score(0.99999994)=2.8E-44 > score(1.0)=2.7E-44
        at 
__randomizedtesting.SeedInfo.seed([CBE792CF1A8B3225:4078CB7D00FCD42F]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.lucene.search.similarities.BaseSimilarityTestCase.doTestScoring(BaseSimilarityTestCase.java:403)
        at 
org.apache.lucene.search.similarities.BaseSimilarityTestCase.testRandomScoring(BaseSimilarityTestCase.java:355)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
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 
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.base/java.lang.Thread.run(Thread.java:844)


FAILED:  org.apache.solr.cloud.AddReplicaTest.test

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([77B3A9DC50455D4A:FFE79606FEB930B2]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertTrue(Assert.java:54)
        at org.apache.solr.cloud.AddReplicaTest.test(AddReplicaTest.java:103)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 557 lines...]
   [junit4] Suite: org.apache.lucene.search.similarities.TestBasicModelIn
   [junit4]   1> 2.8E-44 = score(DFRSimilarity, doc=0, freq=0.99999994), 
computed from:
   [junit4]   1>   1.4E-45 = boost
   [junit4]   1>   1.20117748E16 = NormalizationH1, computed from: 
   [junit4]   1>     0.99999994 = tf
   [junit4]   1>     9.396944E8 = avgFieldLength
   [junit4]   1>     168.0 = len
   [junit4]   1>   1.56153071E17 = BasicModelIn, computed from: 
   [junit4]   1>     12287.0 = numberOfDocuments
   [junit4]   1>     1.0 = docFreq
   [junit4]   1>   1.2487747E-16 = AfterEffectB, computed from: 
   [junit4]   1>     1.20117748E16 = tfn
   [junit4]   1>     1.0 = totalTermFreq
   [junit4]   1>     1.0 = docFreq
   [junit4]   1> 
   [junit4]   1> 2.7E-44 = score(DFRSimilarity, doc=0, freq=1.0), computed from:
   [junit4]   1>   1.4E-45 = boost
   [junit4]   1>   1.20117758E16 = NormalizationH1, computed from: 
   [junit4]   1>     1.0 = tf
   [junit4]   1>     9.396944E8 = avgFieldLength
   [junit4]   1>     168.0 = len
   [junit4]   1>   1.56153088E17 = BasicModelIn, computed from: 
   [junit4]   1>     12287.0 = numberOfDocuments
   [junit4]   1>     1.0 = docFreq
   [junit4]   1>   1.2487746E-16 = AfterEffectB, computed from: 
   [junit4]   1>     1.20117758E16 = tfn
   [junit4]   1>     1.0 = totalTermFreq
   [junit4]   1>     1.0 = docFreq
   [junit4]   1> 
   [junit4]   1> DFR I(n)B1
   [junit4]   1> 
field="field",maxDoc=20923,docCount=12287,sumTotalTermFreq=11546025078177,sumDocFreq=12287
   [junit4]   1> term="term",docFreq=1,totalTermFreq=1
   [junit4]   1> norm=65 (doc length ~ 168)
   [junit4]   1> freq=1.0
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestBasicModelIn 
-Dtests.method=testRandomScoring -Dtests.seed=CBE792CF1A8B3225 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-UG 
-Dtests.timezone=America/Fortaleza -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 2.26s J1 | TestBasicModelIn.testRandomScoring <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: 
score(0.99999994)=2.8E-44 > score(1.0)=2.7E-44
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([CBE792CF1A8B3225:4078CB7D00FCD42F]:0)
   [junit4]    >        at 
org.apache.lucene.search.similarities.BaseSimilarityTestCase.doTestScoring(BaseSimilarityTestCase.java:403)
   [junit4]    >        at 
org.apache.lucene.search.similarities.BaseSimilarityTestCase.testRandomScoring(BaseSimilarityTestCase.java:355)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> NOTE: test params are: codec=Lucene70, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@44f56597),
 locale=en-UG, timezone=America/Fortaleza
   [junit4]   2> NOTE: Linux 4.10.0-37-generic amd64/Oracle Corporation 10-ea 
(64-bit)/cpus=8,threads=1,free=209187312,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestDemo, 
TestPositiveScoresOnlyCollector, TestRegExp, TestParallelReaderEmptyIndex, 
TestFixedBitDocIdSet, TestIndexWriterOutOfFileDescriptors, TestNorms, TestFSTs, 
TestPhrasePrefixQuery, TestPerFieldDocValuesFormat, TestReqExclBulkScorer, 
TestDocumentsWriterDeleteQueue, TestTragicIndexWriterDeadlock, 
TestSizeBoundedForceMerge, TestFilterSpans, TestBooleanQuery, 
TestDocValuesQueries, TestToken, TestGraphTokenizers, TestSpanExplanations, 
TestTermsEnum2, TestExternalCodecs, TestCharArraySet, TestCodecUtil, 
TestRegexpRandom2, TestBasicModelIn]
   [junit4] Completed [104/475 (1!)] on J1 in 2.31s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 12728 lines...]
   [junit4] Suite: org.apache.solr.cloud.AddReplicaTest
   [junit4]   2> 1637632 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/init-core-data-001
   [junit4]   2> 1637633 WARN  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=12 numCloses=12
   [junit4]   2> 1637633 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1637634 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1637634 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001
   [junit4]   2> 1637635 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1637635 INFO  (Thread-2574) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1637635 INFO  (Thread-2574) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1637637 ERROR (Thread-2574) [    ] 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> 1637735 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:33373
   [junit4]   2> 1637742 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1637742 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1637742 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1637742 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1637745 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@46c9e054{/solr,null,AVAILABLE}
   [junit4]   2> 1637745 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2d7a6469{/solr,null,AVAILABLE}
   [junit4]   2> 1637745 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1c35c519{/solr,null,AVAILABLE}
   [junit4]   2> 1637746 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@27f71568{HTTP/1.1,[http/1.1]}{127.0.0.1:38793}
   [junit4]   2> 1637746 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.e.j.s.Server Started @1639924ms
   [junit4]   2> 1637746 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38793}
   [junit4]   2> 1637746 ERROR (jetty-launcher-2479-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1637746 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1637747 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1637747 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1637747 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-09T09:23:58.957156Z
   [junit4]   2> 1637747 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@669132cb{/solr,null,AVAILABLE}
   [junit4]   2> 1637753 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@649dd165{HTTP/1.1,[http/1.1]}{127.0.0.1:34119}
   [junit4]   2> 1637753 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.e.j.s.Server Started @1639931ms
   [junit4]   2> 1637754 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@613d4dcc{HTTP/1.1,[http/1.1]}{127.0.0.1:39505}
   [junit4]   2> 1637754 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34119}
   [junit4]   2> 1637754 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.e.j.s.Server Started @1639932ms
   [junit4]   2> 1637754 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@705f071f{HTTP/1.1,[http/1.1]}{127.0.0.1:38815}
   [junit4]   2> 1637755 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39505}
   [junit4]   2> 1637755 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.e.j.s.Server Started @1639933ms
   [junit4]   2> 1637755 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=38815}
   [junit4]   2> 1637755 ERROR (jetty-launcher-2479-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1637755 ERROR (jetty-launcher-2479-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1637755 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1637755 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1637755 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-09T09:23:58.966192Z
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-09T09:23:58.966248Z
   [junit4]   2> 1637755 ERROR (jetty-launcher-2479-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1637756 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-11-09T09:23:58.966820Z
   [junit4]   2> 1637758 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1637758 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1637758 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1637759 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1637768 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33373/solr
   [junit4]   2> 1637768 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33373/solr
   [junit4]   2> 1637768 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33373/solr
   [junit4]   2> 1637770 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33373/solr
   [junit4]   2> 1637772 DEBUG (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$$Lambda$174/1336480703@2f04fbec
   [junit4]   2> 1637772 DEBUG (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$$Lambda$174/1336480703@6c913630
   [junit4]   2> 1637772 DEBUG (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$$Lambda$174/1336480703@39e56557
   [junit4]   2> 1637773 DEBUG (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.ZkController Added new OnReconnect listener 
org.apache.solr.cloud.ZkController$$Lambda$174/1336480703@7ad645e9
   [junit4]   2> 1637784 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/clusterprops.json
   [junit4]   2> 1637784 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637785 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterstate.json
   [junit4]   2> 1637785 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637785 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/aliases.json
   [junit4]   2> 1637785 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/security.json
   [junit4]   2> 1637786 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1637787 DEBUG (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/98973741144211466-127.0.0.1:38815_solr-n_0000000000
   [junit4]   2> 1637787 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38815_solr
   [junit4]   2> 1637787 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=98973741144211466-127.0.0.1:38815_solr-n_0000000000) starting
   [junit4]   2> 1637792 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 1637795 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer 
operations
   [junit4]   2> 1637800 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38815_solr
   [junit4]   2> 1637800 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
   [junit4]   2> 1637800 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637800 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0
   [junit4]   2> 1637800 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
   [junit4]   2> 1637800 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637800 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue-work
   [junit4]   2> 1637800 INFO  (zkCallback-2499-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1637800 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/autoscaling.json
   [junit4]   2> 1637801 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1637801 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode 
version 1
   [junit4]   2> 1637801 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1637801 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:38815_solr]
   [junit4]   2> 1637801 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
   [junit4]   2> 1637801 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion -1
   [junit4]   2> 1637801 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
   [junit4]   2> 1637801 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 1637801 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Peeking for top 100 elements. ExcludeSet: []
   [junit4]   2> 1637803 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1637804 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1637804 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/autoscaling/events/.auto_add_replicas
   [junit4]   2> 1637804 DEBUG 
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 1637805 DEBUG (ScheduledTrigger-4947-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: [127.0.0.1:38815_solr]
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/clusterprops.json
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637815 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/clusterprops.json
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterstate.json
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637815 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterstate.json
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/aliases.json
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/aliases.json
   [junit4]   2> 1637815 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/security.json
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/security.json
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/clusterprops.json
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637816 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1637816 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterstate.json
   [junit4]   2> 1637816 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637816 DEBUG (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/98973741144211468-127.0.0.1:39505_solr-n_0000000001
   [junit4]   2> 1637816 DEBUG (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/98973741144211465-127.0.0.1:38793_solr-n_0000000002
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/aliases.json
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/security.json
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: 
/solr/overseer_elect/election/98973741144211466-127.0.0.1:38815_solr-n_0000000000
   [junit4]   2> 1637816 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: 
/solr/overseer_elect/election/98973741144211468-127.0.0.1:39505_solr-n_0000000001
   [junit4]   2> 1637816 DEBUG (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/98973741144211466-127.0.0.1:38815_solr-n_0000000000 to 
know if I could be the leader
   [junit4]   2> 1637816 DEBUG (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/98973741144211468-127.0.0.1:39505_solr-n_0000000001 to 
know if I could be the leader
   [junit4]   2> 1637816 DEBUG (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.ZkController Publish node=127.0.0.1:38793_solr as DOWN
   [junit4]   2> 1637816 DEBUG (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.ZkController Publish node=127.0.0.1:39505_solr as DOWN
   [junit4]   2> 1637817 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1637817 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39505_solr
   [junit4]   2> 1637817 DEBUG (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/overseer_elect/election/98973741144211467-127.0.0.1:34119_solr-n_0000000003
   [junit4]   2> 1637817 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:38793_solr
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: 
/solr/overseer_elect/election/98973741144211465-127.0.0.1:38793_solr-n_0000000002
   [junit4]   2> 1637817 DEBUG (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.LeaderElector Watching path 
/overseer_elect/election/98973741144211465-127.0.0.1:38793_solr-n_0000000002 to 
know if I could be the leader
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637817 DEBUG (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.ZkController Publish node=127.0.0.1:34119_solr as DOWN
   [junit4]   2> 1637817 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637817 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:39505_solr"} current state version: 0
   [junit4]   2> 1637818 INFO  (zkCallback-2501-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
   [junit4]   2> 1637818 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:39505_solr
   [junit4]   2> 1637818 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:38793_solr"} current state version: 0
   [junit4]   2> 1637818 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:38793_solr
   [junit4]   2> 1637826 INFO  (zkCallback-2500-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
   [junit4]   2> 1637826 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637826 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637827 INFO  (zkCallback-2498-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
   [junit4]   2> 1637828 INFO  (zkCallback-2499-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
   [junit4]   2> 1637828 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38815.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1637828 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34119_solr
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1637828 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
   [junit4]   2>   "operation":"downnode",
   [junit4]   2>   "node_name":"127.0.0.1:34119_solr"} current state version: 0
   [junit4]   2> 1637828 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:34119_solr
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/live_nodes
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637828 INFO  (zkCallback-2500-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1637828 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637829 INFO  (zkCallback-2499-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1637829 INFO  (zkCallback-2501-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1637831 INFO  (zkCallback-2498-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1637833 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38815.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637833 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38815.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637834 INFO  (jetty-launcher-2479-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node1/.
   [junit4]   2> 1637858 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39505.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637859 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34119.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637864 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39505.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637864 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39505.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637864 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34119.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637864 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34119.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637865 INFO  (jetty-launcher-2479-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node2/.
   [junit4]   2> 1637865 INFO  (jetty-launcher-2479-thread-4) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node4/.
   [junit4]   2> 1637867 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38793.solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637875 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38793.solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637875 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38793.solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1637876 INFO  (jetty-launcher-2479-thread-3) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node3/.
   [junit4]   2> 1637929 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1637929 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1637937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/clusterprops.json
   [junit4]   2> 1637937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/live_nodes
   [junit4]   2> 1637937 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1637937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/clusterstate.json
   [junit4]   2> 1637937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637937 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/aliases.json
   [junit4]   2> 1637938 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33373/solr ready
   [junit4]   2> 1637947 INFO  
(TEST-AddReplicaTest.test-seed#[77B3A9DC50455D4A]) [    ] o.a.s.SolrTestCaseJ4 
###Starting test
   [junit4]   2> 1637950 INFO  (qtp1055207258-12956) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=addreplicatest_coll&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1637951 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1637951 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/overseer/collection-queue-work/qnr-0000000000
   [junit4]   2> 1637951 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/collection-queue-work
   [junit4]   2> 1637951 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.ZkDistributedQueue Found child node with improper name: 
qnr-0000000000
   [junit4]   2> 1637951 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Returning topN elements: 
[/overseer/collection-queue-work/qn-0000000000, ]
   [junit4]   2> 1637951 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : 
[[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]]
   [junit4]   2> 1637951 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor Marked task 
[/overseer/collection-queue-work/qn-0000000000] as running
   [junit4]   2> 1637951 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get 
the message id:/overseer/collection-queue-work/qn-0000000000 message:{
   [junit4]   2>   "name":"addreplicatest_coll",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "collection.configName":"conf1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"2",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"}
   [junit4]   2> 1637951 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.OverseerTaskProcessor Runner processing 
/overseer/collection-queue-work/qn-0000000000
   [junit4]   2> 1637952 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1
   [junit4]   2> 1637952 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.OverseerCollectionMessageHandler 
OverseerCollectionMessageHandler.processMessage : create , {
   [junit4]   2>   "name":"addreplicatest_coll",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "collection.configName":"conf1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"2",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"}
   [junit4]   2> 1637952 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor RunningTasks: 
[/overseer/collection-queue-work/qn-0000000000]
   [junit4]   2> 1637952 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1637952 INFO  (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Create collection addreplicatest_coll
   [junit4]   2> 1637952 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
   [junit4]   2> 1637952 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: 
[/overseer/collection-queue-work/qn-0000000000]
   [junit4]   2> 1637952 DEBUG 
(OverseerCollectionConfigSetProcessor-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Peeking for top 99 elements. ExcludeSet: 
[/overseer/collection-queue-work/qn-0000000000],[]
   [junit4]   2> 1637952 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.Assign Identify nodes using default
   [junit4]   2> 1637952 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.OverseerCollectionMessageHandler creating collections conf node 
/collections/addreplicatest_coll 
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637952 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/collections
   [junit4]   2> 1637953 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Check for collection zkNode:addreplicatest_coll
   [junit4]   2> 1637953 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Collection zkNode exists
   [junit4]   2> 1637953 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1637953 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1637953 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "name":"addreplicatest_coll",
   [junit4]   2>   "fromApi":"true",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "collection.configName":"conf1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "maxShardsPerNode":"2",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "stateFormat":"2",
   [junit4]   2>   "operation":"create"} current state version: 0
   [junit4]   2> 1637953 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ClusterStateMutator building a new cName: addreplicatest_coll
   [junit4]   2> 1637954 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/addreplicatest_coll/state.json
   [junit4]   2> 1637954 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1637954 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1638054 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Creating SolrCores for new collection 
addreplicatest_coll, shardNames [shard1, shard2] , nrtReplicas : 1, 
tlogReplicas: 0, pullReplicas: 0
   [junit4]   2> 1638055 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Creating core addreplicatest_coll_shard1_replica_n1 
as part of shard shard1 of collection addreplicatest_coll on 
127.0.0.1:38793_solr
   [junit4]   2> 1638055 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1638055 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1638056 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1638056 INFO  
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1638056 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Creating core addreplicatest_coll_shard2_replica_n2 
as part of shard shard2 of collection addreplicatest_coll on 
127.0.0.1:39505_solr
   [junit4]   2> 1638056 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator Old Slice: shard1:{
   [junit4]   2>   "range":"80000000-ffffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1638056 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator New Slice: shard1:{
   [junit4]   2>   "range":"80000000-ffffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node3":{
   [junit4]   2>       "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>       "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1638056 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1638056 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1638056 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Expecting 2 cores but found 0
   [junit4]   2> 1638056 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} current state version: 0
   [junit4]   2> 1638056 INFO  
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1638057 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator Old Slice: shard2:{
   [junit4]   2>   "range":"0-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{}}
   [junit4]   2> 1638057 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator New Slice: shard2:{
   [junit4]   2>   "range":"0-7fffffff",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "replicas":{"core_node4":{
   [junit4]   2>       "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>       "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>       "state":"down",
   [junit4]   2>       "node_name":null,
   [junit4]   2>       "type":"NRT"}}}
   [junit4]   2> 1638157 DEBUG (OverseerThreadFactory-4949-thread-1) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Expecting 2 cores but found 0
   [junit4]   2> 1638157 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/addreplicatest_coll/state.json version: 0
   [junit4]   2> 1638158 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1638159 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1638260 INFO  (qtp1798461558-12962) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=addreplicatest_coll_shard1_replica_n1&action=CREATE&numShards=2&collection=addreplicatest_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1638260 INFO  (qtp823756395-12963) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=addreplicatest_coll_shard2_replica_n2&action=CREATE&numShards=2&collection=addreplicatest_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1638261 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1638261 INFO  (qtp823756395-12963) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1638262 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638262 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638263 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 1638263 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 1638264 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1638264 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1638264 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController Registering watch for collection addreplicatest_coll
   [junit4]   2> 1638264 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
Registering watch for collection addreplicatest_coll
   [junit4]   2> 1638264 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638264 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638264 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController waiting to find shard id in clusterstate for 
addreplicatest_coll_shard1_replica_n1
   [junit4]   2> 1638264 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1638265 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for 
addreplicatest_coll_shard2_replica_n2
   [junit4]   2> 1638265 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1638265 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Will update state for replica: 
core_node3:{"core":"addreplicatest_coll_shard1_replica_n1","base_url":"http://127.0.0.1:38793/solr","node_name":"127.0.0.1:38793_solr","state":"down","type":"NRT"}
   [junit4]   2> 1638265 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(addreplicatest_coll//collections/addreplicatest_coll/state.json/1)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"2",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{
   [junit4]   2>     "shard1":{
   [junit4]   2>       "range":"80000000-ffffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node3":{
   [junit4]   2>           "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}},
   [junit4]   2>     "shard2":{
   [junit4]   2>       "range":"0-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node4":{
   [junit4]   2>           "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>           "state":"down",
   [junit4]   2>           "node_name":null,
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 1638265 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:39505_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1638265 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:39505_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1638266 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Will update state for replica: 
core_node4:{"core":"addreplicatest_coll_shard2_replica_n2","base_url":"http://127.0.0.1:39505/solr","node_name":"127.0.0.1:39505_solr","state":"down","type":"NRT"}
   [junit4]   2> 1638266 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(addreplicatest_coll//collections/addreplicatest_coll/state.json/1)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"2",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{
   [junit4]   2>     "shard1":{
   [junit4]   2>       "range":"80000000-ffffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node3":{
   [junit4]   2>           "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}},
   [junit4]   2>     "shard2":{
   [junit4]   2>       "range":"0-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node4":{
   [junit4]   2>           "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:39505_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT"}}}}}
   [junit4]   2> 1638366 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/addreplicatest_coll/state.json version: 1
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638367 INFO  (zkCallback-2501-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1638367 INFO  (zkCallback-2498-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1638367 INFO  (zkCallback-2501-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1638367 INFO  (zkCallback-2498-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638367 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1638368 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1638805 DEBUG (ScheduledTrigger-4947-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: [127.0.0.1:38815_solr, 127.0.0.1:34119_solr, 
127.0.0.1:39505_solr, 127.0.0.1:38793_solr]
   [junit4]   2> 1639272 INFO  (qtp1798461558-12962) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1639272 INFO  (qtp823756395-12963) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1639279 INFO  (qtp1798461558-12962) [    ] o.a.s.s.IndexSchema 
[addreplicatest_coll_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1639280 INFO  (qtp823756395-12963) [    ] o.a.s.s.IndexSchema 
[addreplicatest_coll_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 1639281 INFO  (qtp1798461558-12962) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1639281 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'addreplicatest_coll_shard1_replica_n1' 
using configuration from collection addreplicatest_coll, trusted=true
   [junit4]   2> 1639282 INFO  (qtp1798461558-12962) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_38793.solr.core.addreplicatest_coll.shard1.replica_n1' (registry 
'solr.core.addreplicatest_coll.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1639282 INFO  (qtp1798461558-12962) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1639282 INFO  (qtp823756395-12963) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1639282 INFO  (qtp1798461558-12962) [    ] o.a.s.c.SolrCore 
[[addreplicatest_coll_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node3/addreplicatest_coll_shard1_replica_n1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node3/./addreplicatest_coll_shard1_replica_n1/data/]
   [junit4]   2> 1639282 INFO  (qtp823756395-12963) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'addreplicatest_coll_shard2_replica_n2' 
using configuration from collection addreplicatest_coll, trusted=true
   [junit4]   2> 1639283 INFO  (qtp823756395-12963) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39505.solr.core.addreplicatest_coll.shard2.replica_n2' (registry 
'solr.core.addreplicatest_coll.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@265da7b3
   [junit4]   2> 1639283 INFO  (qtp823756395-12963) [    ] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1639283 INFO  (qtp823756395-12963) [    ] o.a.s.c.SolrCore 
[[addreplicatest_coll_shard2_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node2/addreplicatest_coll_shard2_replica_n2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001/tempDir-001/node2/./addreplicatest_coll_shard2_replica_n2/data/]
   [junit4]   2> 1639321 INFO  (qtp1798461558-12962) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1639321 INFO  (qtp1798461558-12962) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1639321 INFO  (qtp823756395-12963) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1639321 INFO  (qtp823756395-12963) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1639321 INFO  (qtp1798461558-12962) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1639321 INFO  (qtp1798461558-12962) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1639322 INFO  (qtp823756395-12963) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1639322 INFO  (qtp823756395-12963) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1639322 INFO  (qtp1798461558-12962) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@37b70a76[addreplicatest_coll_shard1_replica_n1] main]
   [junit4]   2> 1639323 INFO  (qtp823756395-12963) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@165ca58a[addreplicatest_coll_shard2_replica_n2] main]
   [junit4]   2> 1639323 INFO  (qtp1798461558-12962) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1639323 INFO  (qtp1798461558-12962) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1639323 INFO  (qtp823756395-12963) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1639323 INFO  (qtp823756395-12963) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1639324 INFO  (qtp1798461558-12962) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1639324 INFO  (qtp823756395-12963) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1639324 INFO  (searcherExecutor-4958-thread-1) [    ] 
o.a.s.c.SolrCore [addreplicatest_coll_shard1_replica_n1] Registered new 
searcher Searcher@37b70a76[addreplicatest_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1639324 INFO  (searcherExecutor-4959-thread-1) [    ] 
o.a.s.c.SolrCore [addreplicatest_coll_shard2_replica_n2] Registered new 
searcher Searcher@165ca58a[addreplicatest_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1639324 INFO  (qtp1798461558-12962) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1583579860077379584
   [junit4]   2> 1639324 INFO  (qtp823756395-12963) [    ] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1583579860077379584
   [junit4]   2> 1639326 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 1639326 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
watch zkdir /configs/conf1
   [junit4]   2> 1639326 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/configs/conf1
   [junit4]   2> 1639326 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: create/delete: /solr/configs/conf1
   [junit4]   2> 1639326 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController Register replica - 
core:addreplicatest_coll_shard1_replica_n1 address:http://127.0.0.1:38793/solr 
collection:addreplicatest_coll shard:shard1
   [junit4]   2> 1639326 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
Register replica - core:addreplicatest_coll_shard2_replica_n2 
address:http://127.0.0.1:39505/solr collection:addreplicatest_coll shard:shard2
   [junit4]   2> 1639327 DEBUG (qtp823756395-12963) [    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/collections/addreplicatest_coll/leader_elect/shard2/election/98973741144211468-core_node4-n_0000000000
   [junit4]   2> 1639327 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.LeaderElector Joined leadership election with path: 
/collections/addreplicatest_coll/leader_elect/shard1/election/98973741144211465-core_node3-n_0000000000
   [junit4]   2> 1639328 DEBUG (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard2 
and weAreReplacement=false and leaderVoteWait=10000
   [junit4]   2> 1639328 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 
and weAreReplacement=false and leaderVoteWait=10000
   [junit4]   2> 1639328 INFO  (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1639328 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1639328 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1639328 INFO  (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1639328 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:38793/solr/addreplicatest_coll_shard1_replica_n1/
   [junit4]   2> 1639328 INFO  (qtp823756395-12963) [    ] o.a.s.c.SyncStrategy 
Sync replicas to 
http://127.0.0.1:39505/solr/addreplicatest_coll_shard2_replica_n2/
   [junit4]   2> 1639328 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1639328 INFO  (qtp823756395-12963) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 1639328 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:38793/solr/addreplicatest_coll_shard1_replica_n1/ has no 
replicas
   [junit4]   2> 1639328 INFO  (qtp823756395-12963) [    ] o.a.s.c.SyncStrategy 
http://127.0.0.1:39505/solr/addreplicatest_coll_shard2_replica_n2/ has no 
replicas
   [junit4]   2> 1639328 DEBUG (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContext 127.0.0.1:39505_solr synched 0
   [junit4]   2> 1639328 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContext 127.0.0.1:38793_solr synched 0
   [junit4]   2> 1639328 INFO  (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1639328 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1639328 DEBUG (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/addreplicatest_coll/leaders/shard2/leader after winning as 
/collections/addreplicatest_coll/leader_elect/shard2/election/98973741144211468-core_node4-n_0000000000
   [junit4]   2> 1639328 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/addreplicatest_coll/leaders/shard1/leader after winning as 
/collections/addreplicatest_coll/leader_elect/shard1/election/98973741144211465-core_node3-n_0000000000
   [junit4]   2> 1639329 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1639329 INFO  (qtp823756395-12963) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:39505/solr/addreplicatest_coll_shard2_replica_n2/ shard2
   [junit4]   2> 1639329 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:38793/solr/addreplicatest_coll_shard1_replica_n1/ shard1
   [junit4]   2> 1639329 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1639329 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>   "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>   "state":"active"} current state version: 0
   [junit4]   2> 1639329 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "state":"active"} current state version: 0
   [junit4]   2> 1639430 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ZkStateWriter going to update_collection 
/collections/addreplicatest_coll/state.json version: 2
   [junit4]   2> 1639430 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1639430 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1639431 INFO  (zkCallback-2501-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1639431 INFO  (zkCallback-2498-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1639431 INFO  (zkCallback-2498-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1639431 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1639431 INFO  (zkCallback-2501-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/addreplicatest_coll/state.json] for collection 
[addreplicatest_coll] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 1639431 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1639431 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1639431 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1639431 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: data: /solr/collections/addreplicatest_coll/state.json
   [junit4]   2> 1639432 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1639483 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController We are 
http://127.0.0.1:38793/solr/addreplicatest_coll_shard1_replica_n1/ and leader 
is http://127.0.0.1:38793/solr/addreplicatest_coll_shard1_replica_n1/
   [junit4]   2> 1639484 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController No LogReplay needed for 
core=addreplicatest_coll_shard1_replica_n1 baseURL=http://127.0.0.1:38793/solr
   [junit4]   2> 1639484 INFO  (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1639484 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController publishing state=active
   [junit4]   2> 1639486 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController Found a registered searcher: 
Searcher@37b70a76[addreplicatest_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core: 
org.apache.solr.core.SolrCore@e918560
   [junit4]   2> 1639486 DEBUG (qtp1798461558-12962) [    ] 
o.a.s.c.ZkController Found newest searcher: 
Searcher@37b70a76[addreplicatest_coll_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core: 
addreplicatest_coll_shard1_replica_n1, id: org.apache.solr.core.SolrCore@e918560
   [junit4]   2> 1639487 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1639487 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1639488 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
We are http://127.0.0.1:39505/solr/addreplicatest_coll_shard2_replica_n2/ and 
leader is http://127.0.0.1:39505/solr/addreplicatest_coll_shard2_replica_n2/
   [junit4]   2> 1639488 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1639488 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
No LogReplay needed for core=addreplicatest_coll_shard2_replica_n2 
baseURL=http://127.0.0.1:39505/solr
   [junit4]   2> 1639488 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>   "core_node_name":"core_node3",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 1639488 INFO  (qtp823756395-12963) [    ] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 1639489 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Will update state for replica: 
core_node3:{"core":"addreplicatest_coll_shard1_replica_n1","base_url":"http://127.0.0.1:38793/solr","node_name":"127.0.0.1:38793_solr","state":"active","type":"NRT","leader":"true"}
   [junit4]   2> 1639489 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 1639489 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Collection is now: 
DocCollection(addreplicatest_coll//collections/addreplicatest_coll/state.json/3)={
   [junit4]   2>   "pullReplicas":"0",
   [junit4]   2>   "replicationFactor":"1",
   [junit4]   2>   "router":{"name":"compositeId"},
   [junit4]   2>   "maxShardsPerNode":"2",
   [junit4]   2>   "autoAddReplicas":"false",
   [junit4]   2>   "nrtReplicas":"1",
   [junit4]   2>   "tlogReplicas":"0",
   [junit4]   2>   "shards":{
   [junit4]   2>     "shard1":{
   [junit4]   2>       "range":"80000000-ffffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node3":{
   [junit4]   2>           "core":"addreplicatest_coll_shard1_replica_n1",
   [junit4]   2>           "base_url":"http://127.0.0.1:38793/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:38793_solr",
   [junit4]   2>           "state":"active",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "leader":"true"}}},
   [junit4]   2>     "shard2":{
   [junit4]   2>       "range":"0-7fffffff",
   [junit4]   2>       "state":"active",
   [junit4]   2>       "replicas":{"core_node4":{
   [junit4]   2>           "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>           "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>           "node_name":"127.0.0.1:39505_solr",
   [junit4]   2>           "state":"down",
   [junit4]   2>           "type":"NRT",
   [junit4]   2>           "leader":"true"}}}}}
   [junit4]   2> 1639489 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
Found a registered searcher: 
Searcher@165ca58a[addreplicatest_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core: 
org.apache.solr.core.SolrCore@4292d175
   [junit4]   2> 1639489 DEBUG (qtp823756395-12963) [    ] o.a.s.c.ZkController 
Found newest searcher: Searcher@165ca58a[addreplicatest_coll_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())} for core: 
addreplicatest_coll_shard2_replica_n2, id: 
org.apache.solr.core.SolrCore@4292d175
   [junit4]   2> 1639490 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: children: /solr/overseer/queue
   [junit4]   2> 1639490 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
added: children: /solr/overseer/queue
   [junit4]   2> 1639490 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "core":"addreplicatest_coll_shard2_replica_n2",
   [junit4]   2>   "core_node_name":"core_node4",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:39505/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:39505_solr",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "collection":"addreplicatest_coll",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 1639490 DEBUG 
(OverseerStateUpdate-98973741144211466-127.0.0.1:38815_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Updat

[...truncated too long message...]

eerThreadFactory-4949-thread-3) [    ] o.a.s.c.OverseerTaskProcessor 
RunningTasks: []
   [junit4]   2> 1744070 DEBUG (OverseerThreadFactory-4949-thread-3) [    ] 
o.a.s.c.OverseerTaskProcessor BlockedTasks: []
   [junit4]   2> 1744070 DEBUG (OverseerThreadFactory-4949-thread-3) [    ] 
o.a.s.c.OverseerTaskProcessor CompletedTasks: 
[/overseer/collection-queue-work/qn-0000000003]
   [junit4]   2> 1744070 DEBUG (OverseerThreadFactory-4949-thread-3) [    ] 
o.a.s.c.OverseerTaskProcessor RunningZKTasks: 
[/overseer/collection-queue-work/qn-0000000003]
   [junit4]   2> 1744070 DEBUG (OverseerThreadFactory-4949-thread-3) [    ] 
o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Message 
id:/overseer/collection-queue-work/qn-0000000003 complete, 
response:{success={127.0.0.1:34119_solr={responseHeader={status=0,QTime=0}}},001944791599341444={responseHeader={status=0,QTime=0},STATUS=completed,Response=TaskId:
 001944791599341444 webapp=null path=/admin/cores 
params={async=001944791599341444&qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&name=addreplicatest_coll_shard1_replica_n7&action=CREATE&collection=addreplicatest_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=0},Operation addreplica caused 
exception:=java.lang.InterruptedException: 
org.apache.solr.cloud.OverseerCollectionMessageHandler@17d2ed75 has been 
closed.,exception={msg=org.apache.solr.cloud.OverseerCollectionMessageHandler@17d2ed75
 has been closed.,rspCode=-1}}
   [junit4]   2> 1744070 WARN  
(OverseerAutoScalingTriggerThread-98973741144211466-127.0.0.1:38815_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we 
are closed, exiting.
   [junit4]   2> 1744071 DEBUG (jetty-closer-2480-thread-1) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 1744071 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: create/delete: 
/solr/overseer_elect/election/98973741144211466-127.0.0.1:38815_solr-n_0000000000
   [junit4]   2> 1744071 DEBUG (SyncThread:0) [    ] o.a.s.c.ZkTestServer Watch 
fired: create/delete: 
/solr/collections/addreplicatest_coll/leader_elect/shard1/election/98973741144211466-core_node6-n_0000000001
   [junit4]   2> 1744072 INFO  (jetty-closer-2480-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@46c9e054{/solr,null,UNAVAILABLE}
   [junit4]   2> 1744072 INFO  (zkCallback-2500-thread-3) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34119_solr
   [junit4]   2> 1747348 INFO  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.c.RecoveryStrategy RecoveryStrategy has been closed
   [junit4]   2> 1747348 INFO  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Finished recovery process, successful=[false]
   [junit4]   2> 1747348 INFO  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.c.SolrCore [addreplicatest_coll_shard1_replica_n7]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1f657bd6
   [junit4]   2> 1747348 INFO  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.addreplicatest_coll.shard1.replica_n7, tag=526744534
   [junit4]   2> 1747349 INFO  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@654e9e15: rootName = 
solr_34119, domain = solr.core.addreplicatest_coll.shard1.replica_n7, service 
url = null, agent id = null] for registry 
solr.core.addreplicatest_coll.shard1.replica_n7 / 
com.codahale.metrics.MetricRegistry@2a86f714
   [junit4]   2> 1747357 INFO  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.collection.addreplicatest_coll.shard1.leader, tag=526744534
   [junit4]   2> 1747357 DEBUG (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.c.ZkController removed listener for config directory [/configs/conf1]
   [junit4]   2> 1747357 DEBUG (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.c.ZkController No more listeners for config directory [/configs/conf1]
   [junit4]   2> 1747357 WARN  (recoveryExecutor-2490-thread-1) [    ] 
o.a.s.c.RecoveryStrategy Stopping recovery for 
core=[addreplicatest_coll_shard1_replica_n7] coreNodeName=[core_node8]
   [junit4]   2> 1747358 INFO  (jetty-closer-2480-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2d7a6469{/solr,null,UNAVAILABLE}
   [junit4]   2> 1747358 ERROR 
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
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> 1747359 INFO  
(SUITE-AddReplicaTest-seed#[77B3A9DC50455D4A]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:33373 33373
   [junit4]   2> 1753380 INFO  (Thread-2574) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:33373 33373
   [junit4]   2> 1753380 WARN  (Thread-2574) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/aliases.json
   [junit4]   2>        5       /solr/clusterprops.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2>        4       /solr/configs/conf1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        22      /solr/collections/addreplicatest_coll/state.json
   [junit4]   2>        5       /solr/clusterstate.json
   [junit4]   2>        3       
/solr/overseer_elect/election/98973741144211466-127.0.0.1:38815_solr-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        5       /solr/live_nodes
   [junit4]   2>        5       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AddReplicaTest_77B3A9DC50455D4A-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@46def04d),
 locale=es-GQ, timezone=Africa/Conakry
   [junit4]   2> NOTE: Linux 4.10.0-37-generic amd64/Oracle Corporation 10-ea 
(64-bit)/cpus=8,threads=1,free=381270048,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestJavabinTupleStreamParser, SpellingQueryConverterTest, 
DistribDocExpirationUpdateProcessorTest, SolrGangliaReporterTest, 
QueryEqualityTest, TestPerFieldSimilarity, TestSha256AuthenticationProvider, 
DistributedTermsComponentTest, TestFieldCacheReopen, BasicAuthIntegrationTest, 
UninvertDocValuesMergePolicyTest, TestSubQueryTransformerDistrib, 
ResponseHeaderTest, TestMaxScoreQueryParser, TestAnalyzeInfixSuggestions, 
PrimUtilsTest, SolrCmdDistributorTest, RemoteQueryErrorTest, 
ImplicitSnitchTest, BadIndexSchemaTest, TestSimpleTrackingShardHandler, 
TestInPlaceUpdatesDistrib, TestChildDocTransformer, TestSolrConfigHandler, 
TestPseudoReturnFields, TestReloadDeadlock, ZkFailoverTest, 
TestEmbeddedSolrServerAdminHandler, TestConfigSetProperties, 
TestPullReplicaErrorHandling, TestOmitPositions, TestBM25SimilarityFactory, 
SuggesterFSTTest, TestRangeQuery, OverseerTaskQueueTest, 
MoreLikeThisHandlerTest, TestDocBasedVersionConstraints, TestOrdValues, 
TestCloudInspectUtil, SparseHLLTest, HighlighterMaxOffsetTest, 
TestSolrFieldCacheBean, LargeFieldTest, SolrIndexSplitterTest, 
NotRequiredUniqueKeyTest, ShardRoutingCustomTest, TestFastLRUCache, 
TestSolrConfigHandlerConcurrent, CreateCollectionCleanupTest, DeleteShardTest, 
TestLegacyField, MissingSegmentRecoveryTest, 
LeaderInitiatedRecoveryOnShardRestartTest, DistributedVersionInfoTest, 
TestSchemaVersionResource, CurrencyFieldTypeTest, TestClusterStateMutator, 
AddBlockUpdateTest, TestLegacyTerms, BlockJoinFacetDistribTest, 
DirectUpdateHandlerTest, SolrIndexConfigTest, SolrJmxReporterTest, 
TestRequestStatusCollectionAPI, TolerantUpdateProcessorTest, 
TestDistributedStatsComponentCardinality, ShowFileRequestHandlerTest, 
TestAtomicUpdateErrorCases, AtomicUpdatesTest, SolrRequestParserTest, 
TestComplexPhraseLeadingWildcard, TestDFISimilarityFactory, 
SolrShardReporterTest, HdfsBasicDistributedZk2Test, 
TestCursorMarkWithoutUniqueKey, OutputWriterTest, TestCloudManagedSchema, 
PingRequestHandlerTest, TestPayloadScoreQParserPlugin, TestSchemaNameResource, 
TestManagedStopFilterFactory, SimpleCollectionCreateDeleteTest, 
TestStressRecovery, CircularListTest, CloudMLTQParserTest, TestDocSet, 
TestClusterProperties, TestConfigSetsAPI, TestInfoStreamLogging, 
ZkSolrClientTest, TestDownShardTolerantSearch, NumberUtilsTest, 
SegmentsInfoRequestHandlerTest, TestSmileRequest, PolyFieldTest, ZkCLITest, 
PathHierarchyTokenizerFactoryTest, CloudExitableDirectoryReaderTest, 
AutoScalingHandlerTest, TestFaceting, TestNoOpRegenerator, 
DistributedFacetPivotLargeTest, TestRawTransformer, EnumFieldTest, 
TestAnalyzedSuggestions, TestRemoteStreaming, HdfsLockFactoryTest, 
TestSurroundQueryParser, SchemaVersionSpecificBehaviorTest, 
BigEndianAscendingWordSerializerTest, TestHalfAndHalfDocValues, 
DistributedFacetPivotSmallTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
SaslZkACLProviderTest, CollectionTooManyReplicasTest, TestLuceneMatchVersion, 
TestDynamicFieldCollectionResource, DistributedIntervalFacetingTest, 
ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, 
CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, 
BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, 
ClusterStateUpdateTest, TestRandomFaceting, TestHashPartitioner, 
DistributedSpellCheckComponentTest, ZkControllerTest, TestStressReorder, 
SimpleFacetsTest, SpellCheckComponentTest, QueryElevationComponentTest, 
TestSort, TestBadConfig, TestIndexSearcher, HighlighterTest, 
SoftAutoCommitTest, TestLuceneIndexBackCompat, AddReplicaTest]
   [junit4] Completed [503/749 (1!)] on J1 in 115.77s, 1 test, 1 failure <<< 
FAILURES!

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

Reply via email to