Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4128/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test

Error Message:
Could not find collection:collection2

Stack Trace:
java.lang.AssertionError: Could not find collection:collection2
        at 
__randomizedtesting.SeedInfo.seed([D17A3B0702C1C434:592E04DDAC3DA9CC]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:140)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:135)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:909)
        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:612)
        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:152)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.handler.admin.MetricsHandlerTest.testPropertyFilter

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([D17A3B0702C1C434:B317C546CD4FA40A]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNotNull(Assert.java:526)
        at org.junit.Assert.assertNotNull(Assert.java:537)
        at 
org.apache.solr.handler.admin.MetricsHandlerTest.testPropertyFilter(MetricsHandlerTest.java:201)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11998 lines...]
   [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/init-core-data-001
   [junit4]   2> 1768957 WARN  
(SUITE-FullSolrCloudDistribCmdsTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
   [junit4]   2> 1768957 INFO  
(SUITE-FullSolrCloudDistribCmdsTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1768960 INFO  
(SUITE-FullSolrCloudDistribCmdsTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
 w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 1768960 INFO  
(SUITE-FullSolrCloudDistribCmdsTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: 
/bi_kuw/g
   [junit4]   2> 1768961 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1768962 INFO  (Thread-2392) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1768962 INFO  (Thread-2392) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1768965 ERROR (Thread-2392) [    ] 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> 1769063 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:63797
   [junit4]   2> 1769101 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 1769104 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 1769105 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1769107 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 1769109 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 1769111 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 1769113 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1769115 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1769117 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1769119 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1769121 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 1769123 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly 
asked otherwise
   [junit4]   2> 1769419 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1769420 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4b43bca{/bi_kuw/g,null,AVAILABLE}
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@ac9bae5{HTTP/1.1,[http/1.1]}{127.0.0.1:63805}
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server Started @1772810ms
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/tempDir-001/control/data,
 hostContext=/bi_kuw/g, hostPort=63805, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/control-001/cores}
   [junit4]   2> 1769421 ERROR 
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1769421 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-03T22:30:53.784Z
   [junit4]   2> 1769424 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1769424 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/control-001/solr.xml
   [junit4]   2> 1769429 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1769432 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63797/solr
   [junit4]   2> 1769563 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1769564 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.OverseerElectionContext I am going 
to be the leader 127.0.0.1:63805_bi_kuw%2Fg
   [junit4]   2> 1769565 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.Overseer Overseer 
(id=98246397350838276-127.0.0.1:63805_bi_kuw%2Fg-n_0000000000) starting
   [junit4]   2> 1769577 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.ZkController Register node as live 
in ZooKeeper:/live_nodes/127.0.0.1:63805_bi_kuw%2Fg
   [junit4]   2> 1769579 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (0) -> (1)
   [junit4]   2> 1769638 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1769643 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1769643 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1769644 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/control-001/cores
   [junit4]   2> 1769663 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1769665 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63797/solr ready
   [junit4]   2> 1769670 INFO  (qtp1686098201-14575) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Invoked 
Collection Action :create with params 
replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:63805_bi_kuw%252Fg&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1769672 INFO  
(OverseerThreadFactory-5956-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.CreateCollectionCmd Create 
collection control_collection
   [junit4]   2> 1769780 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1769781 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.TransientSolrCoreCacheDefault 
Allocating transient cache for 4 transient cores
   [junit4]   2> 1769888 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader A cluster state 
change: [WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1770799 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1770806 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema 
[control_collection_shard1_replica_n1] Schema name=test
   [junit4]   2> 1770863 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema 
test/1.6 with uniqueid field id
   [junit4]   2> 1770882 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 
'control_collection_shard1_replica_n1' using configuration from collection 
control_collection, trusted=true
   [junit4]   2> 1770882 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 
'solr.core.control_collection.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1770882 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1770882 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[[control_collection_shard1_replica_n1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/control-001/cores/control_collection_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/control-001/cores/control_collection_shard1_replica_n1/data/]
   [junit4]   2> 1770885 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: 
[TieredMergePolicy: maxMergeAtOnce=4, maxMergeAtOnceExplicit=5, 
maxMergedSegmentMB=82.9794921875, floorSegmentMB=1.91015625, 
forceMergeDeletesPctAllowed=27.178805242355786, segmentsPerTier=47.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.634362735923201
   [junit4]   2> 1770888 WARN  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID 
paramSet a in requestHandler {type = requestHandler,name = /dump,class = 
DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1770940 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1770940 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1770941 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: 
disabled
   [junit4]   2> 1770941 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: 
disabled
   [junit4]   2> 1770947 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy 
RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=34, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1770949 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@56b5ee0d[control_collection_shard1_replica_n1] main]
   [junit4]   2> 1770950 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1770951 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded 
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1770951 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will 
be reserved for  10000
   [junit4]   2> 1770951 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1571942359696932864
   [junit4]   2> 1770952 INFO  
(searcherExecutor-5959-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg 
x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[control_collection_shard1_replica_n1] Registered new searcher 
Searcher@56b5ee0d[control_collection_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1770958 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 1770958 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 1770958 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:63805/bi_kuw/g/control_collection_shard1_replica_n1/
   [junit4]   2> 1770958 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now 
sync replicas to me
   [junit4]   2> 1770958 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:63805/bi_kuw/g/control_collection_shard1_replica_n1/ has no 
replicas
   [junit4]   2> 1770958 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 1770963 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am 
the new leader: 
http://127.0.0.1:63805/bi_kuw/g/control_collection_shard1_replica_n1/ shard1
   [junit4]   2> 1771066 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader A cluster state 
change: [WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1771116 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, 
no recovery necessary
   [junit4]   2> 1771119 INFO  (qtp1686098201-14577) 
[n:127.0.0.1:63805_bi_kuw%2Fg c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1338
   [junit4]   2> 1771122 INFO  (qtp1686098201-14575) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Wait for new 
collection to be active for at most 30 seconds. Check all shard replicas
   [junit4]   2> 1771221 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader A cluster state 
change: [WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1771674 INFO  
(OverseerCollectionConfigSetProcessor-98246397350838276-127.0.0.1:63805_bi_kuw%2Fg-n_0000000000)
 [n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1772132 INFO  (qtp1686098201-14575) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:63805_bi_kuw%252Fg&wt=javabin&version=2}
 status=0 QTime=2462
   [junit4]   2> 1772145 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1772148 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63797/solr ready
   [junit4]   2> 1772148 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 1772150 INFO  (qtp1686098201-14580) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Invoked 
Collection Action :create with params 
replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=&stateFormat=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1772158 INFO  
(OverseerThreadFactory-5956-thread-2-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.CreateCollectionCmd Create 
collection collection1
   [junit4]   2> 1772161 WARN  
(OverseerThreadFactory-5956-thread-2-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.CreateCollectionCmd It is unusual to 
create a collection (collection1) without cores.
   [junit4]   2> 1772387 INFO  (qtp1686098201-14580) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Wait for new 
collection to be active for at most 30 seconds. Check all shard replicas
   [junit4]   2> 1772387 INFO  (qtp1686098201-14580) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=3&createNodeSet=&stateFormat=1&wt=javabin&version=2}
 status=0 QTime=236
   [junit4]   2> 1772676 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-1-001
 of type NRT
   [junit4]   2> 1772676 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1772678 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3d7773c4{/bi_kuw/g,null,AVAILABLE}
   [junit4]   2> 1772678 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2b157508{HTTP/1.1,[http/1.1]}{127.0.0.1:63825}
   [junit4]   2> 1772678 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server Started @1776067ms
   [junit4]   2> 1772678 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/bi_kuw/g, hostPort=63825, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-1-001/cores}
   [junit4]   2> 1772679 ERROR 
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1772679 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1772679 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1772679 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1772679 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-03T22:30:57.042Z
   [junit4]   2> 1772682 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1772682 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-1-001/solr.xml
   [junit4]   2> 1772687 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1772690 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63797/solr
   [junit4]   2> 1772703 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (0) -> (1)
   [junit4]   2> 1772706 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1772709 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.ZkController Register node as live 
in ZooKeeper:/live_nodes/127.0.0.1:63825_bi_kuw%2Fg
   [junit4]   2> 1772711 INFO  (zkCallback-2026-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1772711 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (1) -> (2)
   [junit4]   2> 1772712 INFO  
(zkCallback-2031-thread-1-processing-n:127.0.0.1:63825_bi_kuw%2Fg) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (1) -> (2)
   [junit4]   2> 1772795 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1772799 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1772799 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1772800 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-1-001/cores
   [junit4]   2> 1772820 INFO  (qtp1686098201-14573) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Invoked 
Collection Action :addreplica with params 
node=127.0.0.1:63825_bi_kuw%252Fg&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1772822 INFO  
(OverseerThreadFactory-5956-thread-3-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.AddReplicaCmd addReplica() : {
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "node":"127.0.0.1:63825_bi_kuw%2Fg",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"addreplica"}
   [junit4]   2> 1772822 INFO  
(OverseerThreadFactory-5956-thread-3-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:63825_bi_kuw%2Fg for creating new replica
   [junit4]   2> 1772823 INFO  
(OverseerCollectionConfigSetProcessor-98246397350838276-127.0.0.1:63805_bi_kuw%2Fg-n_0000000000)
 [n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1772825 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n0&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1772826 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.TransientSolrCoreCacheDefault 
Allocating transient cache for 4 transient cores
   [junit4]   2> 1773866 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
8.0.0
   [junit4]   2> 1773876 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.s.IndexSchema 
[collection1_shard2_replica_n0] Schema name=test
   [junit4]   2> 1773933 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.s.IndexSchema Loaded schema test/1.6 
with uniqueid field id
   [junit4]   2> 1773952 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.CoreContainer Creating SolrCore 
'collection1_shard2_replica_n0' using configuration from collection 
collection1, trusted=true
   [junit4]   2> 1773953 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard2.replica_n0' (registry 
'solr.core.collection1.shard2.replica_n0') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1773953 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1773953 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SolrCore 
[[collection1_shard2_replica_n0] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-1-001/cores/collection1_shard2_replica_n0],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-1-001/cores/collection1_shard2_replica_n0/data/]
   [junit4]   2> 1773954 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.RandomMergePolicy RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=4, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=82.9794921875, 
floorSegmentMB=1.91015625, forceMergeDeletesPctAllowed=27.178805242355786, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.634362735923201
   [junit4]   2> 1773956 WARN  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.RequestHandlers INVALID paramSet a in 
requestHandler {type = requestHandler,name = /dump,class = 
DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1773998 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1773998 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1773999 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1773999 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1774000 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.RandomMergePolicy RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1774000 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7fb3b9b5[collection1_shard2_replica_n0] main]
   [junit4]   2> 1774002 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1774002 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1774002 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1774003 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1571942362897186816
   [junit4]   2> 1774003 INFO  
(searcherExecutor-5970-thread-1-processing-n:127.0.0.1:63825_bi_kuw%2Fg 
x:collection1_shard2_replica_n0 s:shard2 c:collection1) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SolrCore 
[collection1_shard2_replica_n0] Registered new searcher 
Searcher@7fb3b9b5[collection1_shard2_replica_n0] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1774009 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 1774009 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.ShardLeaderElectionContext I may be 
the new leader - try and sync
   [junit4]   2> 1774009 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:63825/bi_kuw/g/collection1_shard2_replica_n0/
   [junit4]   2> 1774010 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 1774010 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.SyncStrategy 
http://127.0.0.1:63825/bi_kuw/g/collection1_shard2_replica_n0/ has no replicas
   [junit4]   2> 1774010 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 1774015 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.ShardLeaderElectionContext I am the 
new leader: http://127.0.0.1:63825/bi_kuw/g/collection1_shard2_replica_n0/ 
shard2
   [junit4]   2> 1774184 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 1774189 INFO  (qtp2109775799-14616) 
[n:127.0.0.1:63825_bi_kuw%2Fg c:collection1 s:shard2  
x:collection1_shard2_replica_n0] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n0&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1364
   [junit4]   2> 1774195 INFO  (qtp1686098201-14573) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={node=127.0.0.1:63825_bi_kuw%252Fg&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2}
 status=0 QTime=1375
   [junit4]   2> 1774477 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-2-001
 of type NRT
   [junit4]   2> 1774477 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1774479 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6eda9420{/bi_kuw/g,null,AVAILABLE}
   [junit4]   2> 1774479 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@630b09d5{HTTP/1.1,[http/1.1]}{127.0.0.1:63830}
   [junit4]   2> 1774479 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server Started @1777868ms
   [junit4]   2> 1774479 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/tempDir-001/jetty2,
 replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/bi_kuw/g, 
hostPort=63830, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-2-001/cores}
   [junit4]   2> 1774480 ERROR 
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1774480 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1774480 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1774480 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1774480 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-03T22:30:58.843Z
   [junit4]   2> 1774483 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1774483 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-2-001/solr.xml
   [junit4]   2> 1774488 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1774491 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63797/solr
   [junit4]   2> 1774503 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (0) -> (2)
   [junit4]   2> 1774506 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1774509 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.ZkController Register node as live 
in ZooKeeper:/live_nodes/127.0.0.1:63830_bi_kuw%2Fg
   [junit4]   2> 1774511 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (2) -> (3)
   [junit4]   2> 1774511 INFO  (zkCallback-2026-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1774511 INFO  
(zkCallback-2037-thread-1-processing-n:127.0.0.1:63830_bi_kuw%2Fg) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (2) -> (3)
   [junit4]   2> 1774511 INFO  
(zkCallback-2031-thread-1-processing-n:127.0.0.1:63825_bi_kuw%2Fg) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (2) -> (3)
   [junit4]   2> 1774570 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1774574 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1774574 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1774575 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-2-001/cores
   [junit4]   2> 1774599 INFO  (qtp1686098201-14576) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Invoked 
Collection Action :addreplica with params 
node=127.0.0.1:63830_bi_kuw%252Fg&action=ADDREPLICA&collection=collection1&shard=shard3&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1774602 INFO  
(OverseerThreadFactory-5956-thread-4-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.AddReplicaCmd addReplica() : {
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "node":"127.0.0.1:63830_bi_kuw%2Fg",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"addreplica"}
   [junit4]   2> 1774602 INFO  
(OverseerThreadFactory-5956-thread-4-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:63830_bi_kuw%2Fg for creating new replica
   [junit4]   2> 1774602 INFO  
(OverseerCollectionConfigSetProcessor-98246397350838276-127.0.0.1:63805_bi_kuw%2Fg-n_0000000000)
 [n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1774605 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard3_replica_n0&action=CREATE&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1774606 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.TransientSolrCoreCacheDefault 
Allocating transient cache for 4 transient cores
   [junit4]   2> 1775626 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
8.0.0
   [junit4]   2> 1775634 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.s.IndexSchema 
[collection1_shard3_replica_n0] Schema name=test
   [junit4]   2> 1775668 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.s.IndexSchema Loaded schema test/1.6 
with uniqueid field id
   [junit4]   2> 1775689 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.CoreContainer Creating SolrCore 
'collection1_shard3_replica_n0' using configuration from collection 
collection1, trusted=true
   [junit4]   2> 1775689 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard3.replica_n0' (registry 
'solr.core.collection1.shard3.replica_n0') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1775689 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1775689 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SolrCore 
[[collection1_shard3_replica_n0] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-2-001/cores/collection1_shard3_replica_n0],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-2-001/cores/collection1_shard3_replica_n0/data/]
   [junit4]   2> 1775691 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.RandomMergePolicy RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=4, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=82.9794921875, 
floorSegmentMB=1.91015625, forceMergeDeletesPctAllowed=27.178805242355786, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.634362735923201
   [junit4]   2> 1775693 WARN  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.RequestHandlers INVALID paramSet a in 
requestHandler {type = requestHandler,name = /dump,class = 
DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1775738 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1775738 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1775739 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1775739 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1775740 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.RandomMergePolicy RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1775740 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@44eb2bb7[collection1_shard3_replica_n0] main]
   [junit4]   2> 1775742 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1775743 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1775743 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1775743 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1571942364721709056
   [junit4]   2> 1775744 INFO  
(searcherExecutor-5981-thread-1-processing-n:127.0.0.1:63830_bi_kuw%2Fg 
x:collection1_shard3_replica_n0 s:shard3 c:collection1) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SolrCore 
[collection1_shard3_replica_n0] Registered new searcher 
Searcher@44eb2bb7[collection1_shard3_replica_n0] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1775750 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 1775750 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.ShardLeaderElectionContext I may be 
the new leader - try and sync
   [junit4]   2> 1775750 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:63830/bi_kuw/g/collection1_shard3_replica_n0/
   [junit4]   2> 1775750 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 1775750 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.SyncStrategy 
http://127.0.0.1:63830/bi_kuw/g/collection1_shard3_replica_n0/ has no replicas
   [junit4]   2> 1775750 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 1775754 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.ShardLeaderElectionContext I am the 
new leader: http://127.0.0.1:63830/bi_kuw/g/collection1_shard3_replica_n0/ 
shard3
   [junit4]   2> 1775924 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 1775927 INFO  (qtp1798152354-14645) 
[n:127.0.0.1:63830_bi_kuw%2Fg c:collection1 s:shard3  
x:collection1_shard3_replica_n0] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard3_replica_n0&action=CREATE&collection=collection1&shard=shard3&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1322
   [junit4]   2> 1775930 INFO  (qtp1686098201-14576) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={node=127.0.0.1:63830_bi_kuw%252Fg&action=ADDREPLICA&collection=collection1&shard=shard3&type=NRT&wt=javabin&version=2}
 status=0 QTime=1330
   [junit4]   2> 1776199 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-3-001
 of type NRT
   [junit4]   2> 1776199 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1776201 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@79f78b6{/bi_kuw/g,null,AVAILABLE}
   [junit4]   2> 1776201 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@386fbf78{HTTP/1.1,[http/1.1]}{127.0.0.1:63836}
   [junit4]   2> 1776201 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server Started @1779590ms
   [junit4]   2> 1776201 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/tempDir-001/jetty3,
 replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/bi_kuw/g, 
hostPort=63836, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-3-001/cores}
   [junit4]   2> 1776202 ERROR 
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1776202 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1776202 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1776202 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1776202 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-03T22:31:00.565Z
   [junit4]   2> 1776206 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1776206 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-3-001/solr.xml
   [junit4]   2> 1776210 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1776213 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63797/solr
   [junit4]   2> 1776226 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (0) -> (3)
   [junit4]   2> 1776230 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1776232 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.ZkController Register node as live 
in ZooKeeper:/live_nodes/127.0.0.1:63836_bi_kuw%2Fg
   [junit4]   2> 1776234 INFO  (zkCallback-2026-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1776234 INFO  
(zkCallback-2037-thread-1-processing-n:127.0.0.1:63830_bi_kuw%2Fg) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (3) -> (4)
   [junit4]   2> 1776235 INFO  
(zkCallback-2019-thread-1-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (3) -> (4)
   [junit4]   2> 1776235 INFO  
(zkCallback-2031-thread-1-processing-n:127.0.0.1:63825_bi_kuw%2Fg) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (3) -> (4)
   [junit4]   2> 1776235 INFO  
(zkCallback-2043-thread-1-processing-n:127.0.0.1:63836_bi_kuw%2Fg) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (3) -> (4)
   [junit4]   2> 1776326 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1776331 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1776332 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1776333 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-3-001/cores
   [junit4]   2> 1776366 INFO  (qtp368326310-14674) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.h.a.CollectionsHandler Invoked 
Collection Action :addreplica with params 
node=127.0.0.1:63836_bi_kuw%252Fg&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1776368 INFO  
(OverseerThreadFactory-5956-thread-5-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.AddReplicaCmd addReplica() : {
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "node":"127.0.0.1:63836_bi_kuw%2Fg",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "operation":"addreplica"}
   [junit4]   2> 1776369 INFO  
(OverseerThreadFactory-5956-thread-5-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.AddReplicaCmd Node Identified 
127.0.0.1:63836_bi_kuw%2Fg for creating new replica
   [junit4]   2> 1776369 INFO  
(OverseerCollectionConfigSetProcessor-98246397350838276-127.0.0.1:63805_bi_kuw%2Fg-n_0000000000)
 [n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1776370 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n0&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1776371 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.TransientSolrCoreCacheDefault 
Allocating transient cache for 4 transient cores
   [junit4]   2> 1777398 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SolrConfig Using Lucene MatchVersion: 
8.0.0
   [junit4]   2> 1777407 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.s.IndexSchema 
[collection1_shard1_replica_n0] Schema name=test
   [junit4]   2> 1777447 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.s.IndexSchema Loaded schema test/1.6 
with uniqueid field id
   [junit4]   2> 1777472 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.CoreContainer Creating SolrCore 
'collection1_shard1_replica_n0' using configuration from collection 
collection1, trusted=true
   [junit4]   2> 1777473 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard1.replica_n0' (registry 
'solr.core.collection1.shard1.replica_n0') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1777473 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1777473 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SolrCore 
[[collection1_shard1_replica_n0] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-3-001/cores/collection1_shard1_replica_n0],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-3-001/cores/collection1_shard1_replica_n0/data/]
   [junit4]   2> 1777474 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.RandomMergePolicy RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=4, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=82.9794921875, 
floorSegmentMB=1.91015625, forceMergeDeletesPctAllowed=27.178805242355786, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.634362735923201
   [junit4]   2> 1777477 WARN  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.RequestHandlers INVALID paramSet a in 
requestHandler {type = requestHandler,name = /dump,class = 
DumpRequestHandler,attributes = {initParams=a, name=/dump, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 1777523 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.UpdateHandler Using UpdateLog 
implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1777523 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.UpdateLog Initializing UpdateLog: 
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1777524 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1777524 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1777525 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.RandomMergePolicy RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=34, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 1777526 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@180e444f[collection1_shard1_replica_n0] main]
   [junit4]   2> 1777527 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.r.ManagedResourceStorage Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 1777528 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.r.ManagedResourceStorage Loaded null at 
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1777528 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.h.ReplicationHandler Commits will be 
reserved for  10000
   [junit4]   2> 1777528 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.u.UpdateLog Could not find max version 
in index or recent updates, using new clock 1571942366593417216
   [junit4]   2> 1777529 INFO  
(searcherExecutor-5992-thread-1-processing-n:127.0.0.1:63836_bi_kuw%2Fg 
x:collection1_shard1_replica_n0 s:shard1 c:collection1) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SolrCore 
[collection1_shard1_replica_n0] Registered new searcher 
Searcher@180e444f[collection1_shard1_replica_n0] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1777536 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.ShardLeaderElectionContext Enough 
replicas found to continue.
   [junit4]   2> 1777536 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.ShardLeaderElectionContext I may be 
the new leader - try and sync
   [junit4]   2> 1777537 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:63836/bi_kuw/g/collection1_shard1_replica_n0/
   [junit4]   2> 1777537 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 1777537 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.SyncStrategy 
http://127.0.0.1:63836/bi_kuw/g/collection1_shard1_replica_n0/ has no replicas
   [junit4]   2> 1777537 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 1777541 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.ShardLeaderElectionContext I am the 
new leader: http://127.0.0.1:63836/bi_kuw/g/collection1_shard1_replica_n0/ 
shard1
   [junit4]   2> 1777649 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.c.ZkController I am the leader, no 
recovery necessary
   [junit4]   2> 1777652 INFO  (qtp368326310-14676) 
[n:127.0.0.1:63836_bi_kuw%2Fg c:collection1 s:shard1  
x:collection1_shard1_replica_n0] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n0&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1281
   [junit4]   2> 1777655 INFO  (qtp368326310-14674) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={node=127.0.0.1:63836_bi_kuw%252Fg&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
 status=0 QTime=1288
   [junit4]   2> 1778339 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-4-001
 of type NRT
   [junit4]   2> 1778340 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1778341 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@201129af{/bi_kuw/g,null,AVAILABLE}
   [junit4]   2> 1778342 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1c647765{HTTP/1.1,[http/1.1]}{127.0.0.1:63846}
   [junit4]   2> 1778343 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.e.j.s.Server Started @1781731ms
   [junit4]   2> 1778343 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/tempDir-001/jetty4,
 solrconfig=solrconfig.xml, hostContext=/bi_kuw/g, hostPort=63846, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-4-001/cores}
   [junit4]   2> 1778343 ERROR 
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1778344 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1778344 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1778344 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1778344 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-03T22:31:02.707Z
   [junit4]   2> 1778347 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1778348 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.FullSolrCloudDistribCmdsTest_D17A3B0702C1C434-001/shard-4-001/solr.xml
   [junit4]   2> 1778352 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1778356 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63797/solr
   [junit4]   2> 1778370 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63846_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (0) -> (4)
   [junit4]   2> 1778371 INFO  
(OverseerCollectionConfigSetProcessor-98246397350838276-127.0.0.1:63805_bi_kuw%2Fg-n_0000000000)
 [n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1778374 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63846_bi_kuw%2Fg    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1778380 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63846_bi_kuw%2Fg    ] o.a.s.c.ZkController Register node as live 
in ZooKeeper:/live_nodes/127.0.0.1:63846_bi_kuw%2Fg
   [junit4]   2> 1778383 INFO  
(zkCallback-2043-thread-1-processing-n:127.0.0.1:63836_bi_kuw%2Fg) 
[n:127.0.0.1:63836_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (4) -> (5)
   [junit4]   2> 1778384 INFO  
(zkCallback-2031-thread-1-processing-n:127.0.0.1:63825_bi_kuw%2Fg) 
[n:127.0.0.1:63825_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (4) -> (5)
   [junit4]   2> 1778384 INFO  
(zkCallback-2019-thread-2-processing-n:127.0.0.1:63805_bi_kuw%2Fg) 
[n:127.0.0.1:63805_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (4) -> (5)
   [junit4]   2> 1778384 INFO  
(zkCallback-2037-thread-1-processing-n:127.0.0.1:63830_bi_kuw%2Fg) 
[n:127.0.0.1:63830_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (4) -> (5)
   [junit4]   2> 1778384 INFO  (zkCallback-2026-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1778385 INFO  
(zkCallback-2049-thread-1-processing-n:127.0.0.1:63846_bi_kuw%2Fg) 
[n:127.0.0.1:63846_bi_kuw%2Fg    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (4) -> (5)
   [junit4]   2> 1778498 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63846_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@363fe49
   [junit4]   2> 1778503 INFO  
(TEST-FullSolrCloudDistribCmdsTest.test-seed#[D17A3B0702C1C434]) 
[n:127.0.0.1:63846_bi_kuw%2Fg    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (

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

ecutor-11485-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5b9e65c9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2860577 INFO  
(TEST-MetricsHandlerTest.testCompact-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCompact
   [junit4]   2> 2860648 INFO  
(TEST-MetricsHandlerTest.testCompact-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2860675 INFO  
(TEST-MetricsHandlerTest.testCompact-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testCompact
   [junit4]   2> 2860686 INFO  
(TEST-MetricsHandlerTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2860823 INFO  
(TEST-MetricsHandlerTest.test-seed#[D17A3B0702C1C434]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 2860824 INFO  
(SUITE-MetricsHandlerTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2860824 INFO  
(SUITE-MetricsHandlerTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1846782182
   [junit4]   2> 2860824 INFO  
(SUITE-MetricsHandlerTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2860826 INFO  
(SUITE-MetricsHandlerTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2860832 INFO  
(SUITE-MetricsHandlerTest-seed#[D17A3B0702C1C434]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2860833 INFO  (coreCloseExecutor-11490-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@70c985d0
   [junit4]   2> 2860834 INFO  (coreCloseExecutor-11490-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1892255184
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.admin.MetricsHandlerTest_D17A3B0702C1C434-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1713, maxMBSortInHeap=6.556498979131229, 
sim=RandomSimilarity(queryNorm=false): {}, locale=fr-CH, 
timezone=Pacific/Pohnpei
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=151516840,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [InfoHandlerTest, 
RecoveryZkTest, IndexBasedSpellCheckerTest, BlockJoinFacetSimpleTest, 
PeerSyncTest, TestFieldSortValues, TestMaxScoreQueryParser, 
TestUseDocValuesAsStored, RecoveryAfterSoftCommitTest, 
TestConfigSetsAPIZkFailure, UniqFieldsUpdateProcessorFactoryTest, 
SolrXmlInZkTest, CoreAdminRequestStatusTest, TestRawTransformer, 
URLClassifyProcessorTest, AnalyticsMergeStrategyTest, TestChildDocTransformer, 
XsltUpdateRequestHandlerTest, PropertiesRequestHandlerTest, 
WrapperMergePolicyFactoryTest, PeerSyncWithIndexFingerprintCachingTest, 
CollectionsAPISolrJTest, MultiThreadedOCPTest, TestXIncludeConfig, 
SparseHLLTest, ChaosMonkeyNothingIsSafeTest, 
BigEndianAscendingWordSerializerTest, TestManagedSchema, TestStressReorder, 
TestLegacyNumericUtils, TestBinaryField, 
HdfsTlogReplayBufferedWhileIndexingTest, DateFieldTest, 
TestSolrCloudWithHadoopAuthPlugin, TestStressRecovery, 
DistributedVersionInfoTest, CoreSorterTest, PathHierarchyTokenizerFactoryTest, 
TestCollapseQParserPlugin, ReturnFieldsTest, TestCSVResponseWriter, 
TestBinaryResponseWriter, DistributedFacetPivotSmallTest, 
TestAuthorizationFramework, SegmentsInfoRequestHandlerTest, TestWriterPerf, 
TestCloudJSONFacetJoinDomain, DirectSolrConnectionTest, 
TestIBSimilarityFactory, TestInfoStreamLogging, TestHashPartitioner, 
TestClusterStateMutator, TestSolrCloudSnapshots, XmlUpdateRequestHandlerTest, 
TestCursorMarkWithoutUniqueKey, SSLMigrationTest, TestStressUserVersions, 
TestQuerySenderListener, SoftAutoCommitTest, ResponseHeaderTest, 
ClusterStateTest, TestNamedUpdateProcessors, RequestLoggingTest, 
TestJsonFacetRefinement, SolrMetricManagerTest, TestShardHandlerFactory, 
TestPseudoReturnFields, OutOfBoxZkACLAndCredentialsProvidersTest, 
AnalysisAfterCoreReloadTest, CursorPagingTest, DirectUpdateHandlerOptimizeTest, 
TestMiniSolrCloudClusterSSL, TestSurroundQueryParser, TestReplicationHandler, 
TestFoldingMultitermQuery, SimpleFacetsTest, TestCorePropertiesReload, 
TestFieldTypeCollectionResource, ForceLeaderTest, 
UUIDUpdateProcessorFallbackTest, TestSizeLimitedDistributedMap, 
TestNumericRangeQuery64, TestMinMaxOnMultiValuedField, 
MissingSegmentRecoveryTest, TestScoreJoinQPNoScore, 
TestBlendedInfixSuggestions, TestDistributedSearch, 
TestEmbeddedSolrServerSchemaAPI, HdfsLockFactoryTest, SimpleMLTQParserTest, 
ParsingFieldUpdateProcessorsTest, CachingDirectoryFactoryTest, 
SpellCheckCollatorWithCollapseTest, AtomicUpdateProcessorFactoryTest, 
PrimUtilsTest, TestCloudPseudoReturnFields, RequestHandlersTest, 
ShardSplitTest, TestIndexingPerformance, TestManagedStopFilterFactory, 
TestGraphMLResponseWriter, TestSolrConfigHandlerCloud, 
SuggestComponentContextFilterQueryTest, DeleteNodeTest, TestFunctionQuery, 
SolrCoreTest, CurrencyFieldTypeTest, TestDistributedStatsComponentCardinality, 
TestTolerantUpdateProcessorCloud, TestInPlaceUpdatesStandalone, 
MergeStrategyTest, CircularListTest, SpatialFilterTest, TestExportWriter, 
TestOverriddenPrefixQueryForCustomFieldType, DistanceFunctionTest, TestSort, 
RuleEngineTest, TestPayloadScoreQParserPlugin, TestNumericTerms64, 
TestAtomicUpdateErrorCases, TestMiniSolrCloudCluster, 
TestStressCloudBlindAtomicUpdates, TestDFISimilarityFactory, 
TestCloudDeleteByQuery, AliasIntegrationTest, NotRequiredUniqueKeyTest, 
SimplePostToolTest, JSONWriterTest, BlockCacheTest, AlternateDirectoryTest, 
TestFaceting, ResourceLoaderTest, TestCodecSupport, HdfsSyncSliceTest, 
TestConfigSetProperties, TestLeaderElectionZkExpiry, SuggesterTest, 
TestClassNameShortening, HdfsWriteToMultipleCollectionsTest, 
TestStressLiveNodes, DistributedFacetPivotLongTailTest, TestJsonRequest, 
InfixSuggestersTest, QueryParsingTest, FastVectorHighlighterTest, 
TestDeleteCollectionOnDownNodes, ConfigSetsAPITest, 
TestReversedWildcardFilterFactory, ClassificationUpdateProcessorTest, 
DisMaxRequestHandlerTest, HighlighterTest, 
ChaosMonkeySafeLeaderWithPullReplicasTest, HdfsNNFailoverTest, 
HLLSerializationTest, TestFreeTextSuggestions, 
TestManagedSynonymGraphFilterFactory, ShufflingReplicaListTransformerTest, 
CreateCollectionCleanupTest, TestLRUStatsCache, SortByFunctionTest, 
TestPushWriter, CursorMarkTest, ExplicitHLLTest, 
TestTrackingShardHandlerFactory, TestSystemCollAutoCreate, 
SolrGraphiteReporterTest, DocExpirationUpdateProcessorFactoryTest, 
SortSpecParsingTest, TestFuzzyAnalyzedSuggestions, CSVRequestHandlerTest, 
CoreAdminHandlerTest, MetricsConfigTest, TestUpdate, 
DistribJoinFromCollectionTest, ChaosMonkeySafeLeaderTest, 
CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, 
ClusterStateUpdateTest, LeaderElectionTest, TestRandomDVFaceting, 
AutoCommitTest, TestRecovery, TermVectorComponentDistributedTest, 
TestStressVersions, HardAutoCommitTest, TestCoreContainer, TestSolr4Spatial, 
QueryElevationComponentTest, ConvertedLegacyTest, BasicFunctionalityTest, 
DirectUpdateHandlerTest, ShowFileRequestHandlerTest, SolrIndexSplitterTest, 
SignatureUpdateProcessorFactoryTest, DocValuesTest, IndexSchemaTest, 
TestJmxIntegration, MBeansHandlerTest, TestPHPSerializedResponseWriter, 
SearchHandlerTest, HighlighterConfigTest, TestSolrIndexConfig, 
TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, 
SolrIndexConfigTest, BadComponentTest, TestSolrDeletionPolicy2, MultiTermTest, 
SampleTest, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, 
TestElisionMultitermQuery, TestConfig, OutputWriterTest, 
ExternalFileFieldSortTest, TestSolrCoreProperties, TestPhraseSuggestions, 
SpellPossibilityIteratorTest, TestSweetSpotSimilarityFactory, 
TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestFastWriter, 
ScriptEngineTest, DateMathParserTest, RAMDirectoryFactoryTest, TestLRUCache, 
TestDocumentBuilder, SliceStateTest, DistributedMLTComponentTest, 
TestHighlightDedupGrouping, TestTolerantSearch, 
TestEmbeddedSolrServerConstructors, ConnectionReuseTest, 
CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, 
CdcrRequestHandlerTest, CdcrVersionReplicationTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
ConcurrentDeleteAndCreateCollectionTest, ConnectionManagerTest, 
CustomCollectionTest, DeleteStatusTest, 
DistribDocExpirationUpdateProcessorTest, LeaderElectionContextKeyTest, 
LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, 
NodeMutatorTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, 
RemoteQueryErrorTest, ReplaceNodeTest, SharedFSAutoReplicaFailoverUtilsTest, 
SolrCLIZkUtilsTest, SolrCloudExampleTest, TestClusterProperties, 
TestConfigSetsAPI, TestLockTree, TestPrepRecovery, TestRandomFlRTGCloud, 
TestRandomRequestDistribution, TestRequestForwarding, 
TestRequestStatusCollectionAPI, TestShortCircuitedRequests, 
TestSolrCloudWithSecureImpersonation, TestStressInPlaceUpdates, 
BlobRepositoryCloudTest, ConfigureRecoveryStrategyTest, 
ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, QueryResultKeyTest, 
TestConfigOverlay, TestCustomStream, TestImplicitCoreProperties, 
TestInitParams, TestNRTOpen, TestSimpleTextCodec, TestSolrConfigHandler, 
TestSolrXml, SearchHandlerTest, TestBlobHandler, TestSQLHandlerNonCloud, 
TestSolrConfigHandlerConcurrent, V2StandaloneTest, 
CoreMergeIndexesAdminHandlerTest, MetricsHandlerTest]
   [junit4] Completed [585/725 (2!)] on J0 in 1.67s, 3 tests, 1 failure <<< 
FAILURES!

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

Reply via email to