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

2 tests failed.
FAILED:  org.apache.solr.core.TestSolrConfigHandler.testReqParams

Error Message:
Could not get expected value  'CY val' for path 'params/c' full output: {   
"responseHeader":{     "status":500,     "QTime":0},   "error":{     
"trace":"java.lang.ClassCastException\n",     "code":500}},  from server:  null

Stack Trace:
java.lang.AssertionError: Could not get expected value  'CY val' for path 
'params/c' full output: {
  "responseHeader":{
    "status":500,
    "QTime":0},
  "error":{
    "trace":"java.lang.ClassCastException\n",
    "code":500}},  from server:  null
        at 
__randomizedtesting.SeedInfo.seed([799BD6302803D42B:EB7EE397F9050C6]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
        at 
org.apache.solr.core.TestSolrConfigHandler.testReqParams(TestSolrConfigHandler.java:665)
        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)


FAILED:  org.apache.solr.handler.TestSolrConfigHandlerCloud.test

Error Message:
Could not get expected value  'CY val' for path 'params/c' full output: {   
"responseHeader":{     "status":500,     "QTime":0},   "error":{     
"msg":"[Ljava.lang.String; cannot be cast to java.lang.String",     
"trace":"java.lang.ClassCastException: [Ljava.lang.String; cannot be cast to 
java.lang.String\n\tat 
org.apache.solr.common.params.MapSolrParams.getParams(MapSolrParams.java:39)\n\tat
 
org.apache.solr.common.params.MultiMapSolrParams.asMultiMap(MultiMapSolrParams.java:103)\n\tat
 
org.apache.solr.common.params.MultiMapSolrParams.asMultiMap(MultiMapSolrParams.java:81)\n\tat
 
org.apache.solr.request.json.RequestUtil.processParams(RequestUtil.java:119)\n\tat
 
org.apache.solr.util.SolrPluginUtils.setDefaults(SolrPluginUtils.java:177)\n\tat
 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:175)\n\tat
 org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)\n\tat 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722)\n\tat 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528)\n\tat 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)\n\tat
 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)\n\tat
 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)\n\tat
 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)\n\tat
 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)\n\tat 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)\n\tat
 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)\n\tat
 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat
 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)\n\tat
 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)\n\tat
 org.eclipse.jetty.server.Server.handle(Server.java:534)\n\tat 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)\n\tat 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)\n\tat
 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)\n\tat
 org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)\n\tat 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)\n\tat
 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)\n\tat
 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)\n\tat
 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)\n\tat
 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)\n\tat
 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)\n\tat
 java.lang.Thread.run(Thread.java:748)\n",     "code":500}},  from server:  
http://127.0.0.1:62639/in/v/collection1

Stack Trace:
java.lang.AssertionError: Could not get expected value  'CY val' for path 
'params/c' full output: {
  "responseHeader":{
    "status":500,
    "QTime":0},
  "error":{
    "msg":"[Ljava.lang.String; cannot be cast to java.lang.String",
    "trace":"java.lang.ClassCastException: [Ljava.lang.String; cannot be cast 
to java.lang.String\n\tat 
org.apache.solr.common.params.MapSolrParams.getParams(MapSolrParams.java:39)\n\tat
 
org.apache.solr.common.params.MultiMapSolrParams.asMultiMap(MultiMapSolrParams.java:103)\n\tat
 
org.apache.solr.common.params.MultiMapSolrParams.asMultiMap(MultiMapSolrParams.java:81)\n\tat
 
org.apache.solr.request.json.RequestUtil.processParams(RequestUtil.java:119)\n\tat
 
org.apache.solr.util.SolrPluginUtils.setDefaults(SolrPluginUtils.java:177)\n\tat
 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:175)\n\tat
 org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)\n\tat 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722)\n\tat 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528)\n\tat 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)\n\tat
 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)\n\tat
 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)\n\tat
 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)\n\tat
 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)\n\tat
 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)\n\tat 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)\n\tat
 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)\n\tat
 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat
 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)\n\tat
 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)\n\tat
 org.eclipse.jetty.server.Server.handle(Server.java:534)\n\tat 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)\n\tat 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)\n\tat
 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)\n\tat
 org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)\n\tat 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)\n\tat
 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)\n\tat
 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)\n\tat
 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)\n\tat
 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)\n\tat
 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)\n\tat
 java.lang.Thread.run(Thread.java:748)\n",
    "code":500}},  from server:  http://127.0.0.1:62639/in/v/collection1
        at 
__randomizedtesting.SeedInfo.seed([799BD6302803D42B:F1CFE9EA86FFB9D3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
        at 
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:217)
        at 
org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:68)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 10912 lines...]
   [junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerCloud
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/init-core-data-001
   [junit4]   2> 81868 WARN  
(SUITE-TestSolrConfigHandlerCloud-seed#[799BD6302803D42B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
   [junit4]   2> 81868 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[799BD6302803D42B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 81871 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[799BD6302803D42B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 81871 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[799BD6302803D42B]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /in/v
   [junit4]   2> 81872 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 81873 INFO  (Thread-226) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 81873 INFO  (Thread-226) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 81876 ERROR (Thread-226) [    ] 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> 81983 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:62603
   [junit4]   2> 82046 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82049 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 82051 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82053 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82054 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82056 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82058 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82060 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82062 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82063 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82065 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
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> 82068 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly 
asked otherwise
   [junit4]   2> 82264 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/control-001/cores/collection1
   [junit4]   2> 82266 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 82267 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4b36a76b{/in/v,null,AVAILABLE}
   [junit4]   2> 82269 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@338e929e{HTTP/1.1,[http/1.1]}{127.0.0.1:62606}
   [junit4]   2> 82269 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server Started @85783ms
   [junit4]   2> 82269 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/tempDir-001/control/data,
 replicaType=NRT, hostContext=/in/v, hostPort=62606, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/control-001/cores}
   [junit4]   2> 82269 ERROR 
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 82269 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 82269 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 82270 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 82270 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-13T20:10:02.077Z
   [junit4]   2> 82273 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 82273 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/control-001/solr.xml
   [junit4]   2> 82277 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 82280 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62603/solr
   [junit4]   2> 82313 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 82314 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:62606_in%2Fv
   [junit4]   2> 82315 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.Overseer Overseer 
(id=98132597255766020-127.0.0.1:62606_in%2Fv-n_0000000000) starting
   [junit4]   2> 82326 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62606_in%2Fv
   [junit4]   2> 82328 INFO  
(zkCallback-261-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 82451 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 82453 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 82453 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 82455 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/control-001/cores
   [junit4]   2> 82455 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.CorePropertiesLocator Cores are: 
[collection1]
   [junit4]   2> 82456 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 82460 INFO  
(OverseerStateUpdate-98132597255766020-127.0.0.1:62606_in%2Fv-n_0000000000) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 83503 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 83519 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 83580 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 83596 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection control_collection, trusted=true
   [junit4]   2> 83596 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.control_collection.shard1.core_node1' (registry 
'solr.core.control_collection.shard1.core_node1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 83596 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 83596 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/control-001/cores/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/control-001/cores/collection1/data/]
   [junit4]   2> 83598 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1806706619, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 83600 WARN  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
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> 83639 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 83639 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 83639 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 83639 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 83640 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1654072263, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 83640 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4d84f827[collection1] main]
   [junit4]   2> 83642 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 83642 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 83643 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 83645 INFO  
(searcherExecutor-527-thread-1-processing-n:127.0.0.1:62606_in%2Fv 
x:collection1 c:control_collection) [n:127.0.0.1:62606_in%2Fv 
c:control_collection   x:collection1] o.a.s.c.SolrCore [collection1] Registered 
new searcher Searcher@4d84f827[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 83645 INFO  
(coreLoadExecutor-526-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv c:control_collection   x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1570121557949284352
   [junit4]   2> 83653 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas 
found to continue.
   [junit4]   2> 83653 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new 
leader - try and sync
   [junit4]   2> 83653 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62606/in/v/collection1/
   [junit4]   2> 83653 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync 
replicas to me
   [junit4]   2> 83653 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:62606/in/v/collection1/ has no replicas
   [junit4]   2> 83654 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all 
replicas participating in election, clear LIR
   [junit4]   2> 83659 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new 
leader: http://127.0.0.1:62606/in/v/collection1/ shard1
   [junit4]   2> 83832 INFO  
(coreZkRegister-519-thread-1-processing-n:127.0.0.1:62606_in%2Fv x:collection1 
c:control_collection) [n:127.0.0.1:62606_in%2Fv c:control_collection s:shard1 
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery 
necessary
   [junit4]   2> 84002 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 84006 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62603/solr ready
   [junit4]   2> 84006 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 84006 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 84221 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001/cores/collection1
   [junit4]   2> 84223 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001
 of type NRT
   [junit4]   2> 84223 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 84225 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@31000007{/in/v,null,AVAILABLE}
   [junit4]   2> 84226 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5255c37a{HTTP/1.1,[http/1.1]}{127.0.0.1:62616}
   [junit4]   2> 84226 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server Started @87740ms
   [junit4]   2> 84226 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/tempDir-001/jetty1,
 solrconfig=solrconfig.xml, hostContext=/in/v, hostPort=62616, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001/cores}
   [junit4]   2> 84226 ERROR 
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 84227 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 84227 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 84227 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 84227 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-13T20:10:04.034Z
   [junit4]   2> 84230 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 84230 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001/solr.xml
   [junit4]   2> 84234 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 84237 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62603/solr
   [junit4]   2> 84250 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 84254 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 84257 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62616_in%2Fv
   [junit4]   2> 84259 INFO  
(zkCallback-261-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 84259 INFO  (zkCallback-265-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 84259 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 84355 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 84358 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 84358 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 84360 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001/cores
   [junit4]   2> 84360 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.CorePropertiesLocator Cores are: 
[collection1]
   [junit4]   2> 84362 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 84365 INFO  
(OverseerStateUpdate-98132597255766020-127.0.0.1:62606_in%2Fv-n_0000000000) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard2
   [junit4]   2> 84473 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 85397 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 85410 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 85464 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.s.IndexSchema 
Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 85479 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
collection1, trusted=true
   [junit4]   2> 85479 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard2.core_node1' (registry 
'solr.core.collection1.shard2.core_node1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 85479 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 85479 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001/cores/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-1-001/cores/collection1/data/]
   [junit4]   2> 85485 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1806706619, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 85487 WARN  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
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> 85524 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 85525 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 85525 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 85525 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 85526 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1654072263, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 85526 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7a6faee7[collection1] main]
   [junit4]   2> 85528 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 85528 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 85528 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 85529 INFO  
(coreLoadExecutor-537-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1570121559924801536
   [junit4]   2> 85530 INFO  
(searcherExecutor-538-thread-1-processing-n:127.0.0.1:62616_in%2Fv 
x:collection1 c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1   
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7a6faee7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 85536 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to 
continue.
   [junit4]   2> 85536 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try 
and sync
   [junit4]   2> 85536 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62616/in/v/collection1/
   [junit4]   2> 85536 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 85536 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:62616/in/v/collection1/ 
has no replicas
   [junit4]   2> 85536 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas 
participating in election, clear LIR
   [junit4]   2> 85541 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62616/in/v/collection1/ shard2
   [junit4]   2> 85656 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 85714 INFO  
(coreZkRegister-532-thread-1-processing-n:127.0.0.1:62616_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62616_in%2Fv c:collection1 s:shard2 r:core_node1 
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 85825 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [2])
   [junit4]   2> 86118 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001/cores/collection1
   [junit4]   2> 86119 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001
 of type NRT
   [junit4]   2> 86120 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 86121 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1da5c044{/in/v,null,AVAILABLE}
   [junit4]   2> 86122 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@10c97ec6{HTTP/1.1,[http/1.1]}{127.0.0.1:62630}
   [junit4]   2> 86122 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server Started @89636ms
   [junit4]   2> 86122 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/tempDir-001/jetty2,
 solrconfig=solrconfig.xml, hostContext=/in/v, hostPort=62630, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001/cores}
   [junit4]   2> 86122 ERROR 
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 86123 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 86123 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 86123 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 86123 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-13T20:10:05.930Z
   [junit4]   2> 86126 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 86126 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001/solr.xml
   [junit4]   2> 86131 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 86135 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62603/solr
   [junit4]   2> 86147 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 86151 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 86154 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62630_in%2Fv
   [junit4]   2> 86156 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 86156 INFO  (zkCallback-265-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 86156 INFO  
(zkCallback-261-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 86157 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (3)
   [junit4]   2> 86217 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 86219 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 86220 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 86221 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001/cores
   [junit4]   2> 86221 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.CorePropertiesLocator Cores are: 
[collection1]
   [junit4]   2> 86223 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 86224 INFO  
(OverseerStateUpdate-98132597255766020-127.0.0.1:62606_in%2Fv-n_0000000000) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 86335 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 86335 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 87249 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 87257 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 87312 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.s.IndexSchema 
Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 87329 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
collection1, trusted=true
   [junit4]   2> 87330 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard1.core_node2' (registry 
'solr.core.collection1.shard1.core_node2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 87330 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 87330 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001/cores/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-2-001/cores/collection1/data/]
   [junit4]   2> 87331 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1806706619, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 87333 WARN  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
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> 87374 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 87374 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 87375 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 87375 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 87376 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1654072263, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 87376 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6da5ce13[collection1] main]
   [junit4]   2> 87377 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 87378 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 87378 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 87378 INFO  
(coreLoadExecutor-548-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1570121561863618560
   [junit4]   2> 87379 INFO  
(searcherExecutor-549-thread-1-processing-n:127.0.0.1:62630_in%2Fv 
x:collection1 c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1   
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6da5ce13[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 87385 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to 
continue.
   [junit4]   2> 87385 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try 
and sync
   [junit4]   2> 87385 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62630/in/v/collection1/
   [junit4]   2> 87385 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 87385 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:62630/in/v/collection1/ 
has no replicas
   [junit4]   2> 87385 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas 
participating in election, clear LIR
   [junit4]   2> 87390 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62630/in/v/collection1/ shard1
   [junit4]   2> 87498 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 87498 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 87555 INFO  
(coreZkRegister-543-thread-1-processing-n:127.0.0.1:62630_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62630_in%2Fv c:collection1 s:shard1 r:core_node2 
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 87673 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 87673 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [3])
   [junit4]   2> 88001 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001/cores/collection1
   [junit4]   2> 88002 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001
 of type NRT
   [junit4]   2> 88002 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 88004 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7eeb1485{/in/v,null,AVAILABLE}
   [junit4]   2> 88004 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@32d385a0{HTTP/1.1,[http/1.1]}{127.0.0.1:62634}
   [junit4]   2> 88004 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server Started @91518ms
   [junit4]   2> 88004 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/tempDir-001/jetty3,
 solrconfig=solrconfig.xml, hostContext=/in/v, hostPort=62634, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001/cores}
   [junit4]   2> 88004 ERROR 
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 88005 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 88005 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 88005 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 88005 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-13T20:10:07.812Z
   [junit4]   2> 88008 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 88008 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001/solr.xml
   [junit4]   2> 88013 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 88016 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62603/solr
   [junit4]   2> 88029 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 88032 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 88035 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62634_in%2Fv
   [junit4]   2> 88037 INFO  
(zkCallback-261-thread-1-processing-n:127.0.0.1:62606_in%2Fv) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 88038 INFO  
(zkCallback-283-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 88037 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 88037 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 88037 INFO  (zkCallback-265-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 88104 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 88107 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 88107 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 88109 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.CorePropertiesLocator Found 1 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001/cores
   [junit4]   2> 88109 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.CorePropertiesLocator Cores are: 
[collection1]
   [junit4]   2> 88110 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 88111 INFO  
(OverseerStateUpdate-98132597255766020-127.0.0.1:62606_in%2Fv-n_0000000000) 
[n:127.0.0.1:62606_in%2Fv    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard2
   [junit4]   2> 88220 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 88220 INFO  
(zkCallback-283-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 88220 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 89154 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 89165 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 89223 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.s.IndexSchema 
Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 89239 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
collection1, trusted=true
   [junit4]   2> 89239 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard2.core_node3' (registry 
'solr.core.collection1.shard2.core_node3') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5215b1a3
   [junit4]   2> 89240 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 89240 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.c.SolrCore 
[[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001/cores/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-3-001/cores/collection1/data/]
   [junit4]   2> 89241 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1806706619, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 89243 WARN  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
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> 89281 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 89282 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 89282 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 89282 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 89283 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1654072263, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 89283 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1cbdc864[collection1] main]
   [junit4]   2> 89285 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 89285 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 89285 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 89285 INFO  
(coreLoadExecutor-559-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv c:collection1   x:collection1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1570121563863252992
   [junit4]   2> 89286 INFO  
(searcherExecutor-560-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 c:collection1) [n:127.0.0.1:62634_in%2Fv c:collection1   
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1cbdc864[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 89290 INFO  
(coreZkRegister-554-thread-1-processing-n:127.0.0.1:62634_in%2Fv x:collection1 
c:collection1) [n:127.0.0.1:62634_in%2Fv c:collection1 s:shard2 r:core_node3 
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
   [junit4]   2> 89290 INFO  
(updateExecutor-280-thread-1-processing-n:127.0.0.1:62634_in%2Fv x:collection1 
s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv c:collection1 
s:shard2 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState Running 
recovery
   [junit4]   2> 89290 INFO  
(recoveryExecutor-281-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv 
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy 
Starting recovery process. recoveringAfterStartup=true
   [junit4]   2> 89291 INFO  
(recoveryExecutor-281-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv 
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy 
###### startupVersions=[[]]
   [junit4]   2> 89291 INFO  
(recoveryExecutor-281-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv 
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy 
Begin buffering updates. core=[collection1]
   [junit4]   2> 89291 INFO  
(recoveryExecutor-281-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv 
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting 
to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 89291 INFO  
(recoveryExecutor-281-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv 
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy 
Publishing state of core [collection1] as recovering, leader is 
[http://127.0.0.1:62616/in/v/collection1/] and I am 
[http://127.0.0.1:62634/in/v/collection1/]
   [junit4]   2> 89294 INFO  
(recoveryExecutor-281-thread-1-processing-n:127.0.0.1:62634_in%2Fv 
x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0.0.1:62634_in%2Fv 
c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy 
Sending prep recovery command to [http://127.0.0.1:62616/in/v]; [WaitForState: 
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:62634_in%252Fv&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
   [junit4]   2> 89297 INFO  (qtp309965661-1241) [n:127.0.0.1:62616_in%2Fv    ] 
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state: 
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true, 
maxTime: 183 s
   [junit4]   2> 89298 INFO  (qtp309965661-1241) [n:127.0.0.1:62616_in%2Fv    ] 
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, 
shard=shard2, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader? 
true, live=true, checkLive=true, currentState=down, localState=active, 
nodeName=127.0.0.1:62634_in%2Fv, coreNodeName=core_node3, 
onlyIfActiveCheckResult=false, nodeProps: 
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:62634/in/v","node_name":"127.0.0.1:62634_in%2Fv","state":"down","type":"NRT"}
   [junit4]   2> 89406 INFO  
(zkCallback-277-thread-1-processing-n:127.0.0.1:62630_in%2Fv) 
[n:127.0.0.1:62630_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 89407 INFO  
(zkCallback-283-thread-1-processing-n:127.0.0.1:62634_in%2Fv) 
[n:127.0.0.1:62634_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 89407 INFO  
(zkCallback-271-thread-1-processing-n:127.0.0.1:62616_in%2Fv) 
[n:127.0.0.1:62616_in%2Fv    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 89896 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-4-001/cores/collection1
   [junit4]   2> 89897 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/shard-4-001
 of type NRT
   [junit4]   2> 89897 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 89898 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@46b7db6f{/in/v,null,AVAILABLE}
   [junit4]   2> 89899 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@27f0b9d7{HTTP/1.1,[http/1.1]}{127.0.0.1:62639}
   [junit4]   2> 89899 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.Server Started @93413ms
   [junit4]   2> 89899 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_799BD6302803D42B-001/t

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

d#[799BD6302803D42B]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.jvm, tag=null
   [junit4]   2> 2181695 INFO  
(TEST-TestSolrConfigHandler.testReqParams-seed#[799BD6302803D42B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2181695 INFO  
(TEST-TestSolrConfigHandler.testReqParams-seed#[799BD6302803D42B]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2181696 INFO  (coreCloseExecutor-10812-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@717ef1b4
   [junit4]   2> 2181697 INFO  (coreCloseExecutor-10812-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1904144820
   [junit4]   2> 2181700 INFO  
(TEST-TestSolrConfigHandler.testReqParams-seed#[799BD6302803D42B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@28590772{/solr,null,UNAVAILABLE}
   [junit4]   2> 2181701 INFO  
(TEST-TestSolrConfigHandler.testReqParams-seed#[799BD6302803D42B]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testReqParams
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrConfigHandler -Dtests.method=testReqParams 
-Dtests.seed=799BD6302803D42B -Dtests.slow=true -Dtests.locale=bg 
-Dtests.timezone=Pacific/Ponape -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 6.90s J0 | TestSolrConfigHandler.testReqParams <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Could not get expected 
value  'CY val' for path 'params/c' full output: {
   [junit4]    >   "responseHeader":{
   [junit4]    >     "status":500,
   [junit4]    >     "QTime":0},
   [junit4]    >   "error":{
   [junit4]    >     "trace":"java.lang.ClassCastException\n",
   [junit4]    >     "code":500}},  from server:  null
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([799BD6302803D42B:EB7EE397F9050C6]:0)
   [junit4]    >        at 
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:556)
   [junit4]    >        at 
org.apache.solr.core.TestSolrConfigHandler.testReqParams(TestSolrConfigHandler.java:665)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.core.TestSolrConfigHandler_799BD6302803D42B-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=120, maxMBSortInHeap=6.834586106200485, 
sim=RandomSimilarity(queryNorm=false): {}, locale=bg, timezone=Pacific/Ponape
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=208674240,total=533725184
   [junit4]   2> NOTE: All tests run in this JVM: [TestInitQParser, 
CopyFieldTest, TestPerFieldSimilarity, TestBlendedInfixSuggestions, 
BasicAuthStandaloneTest, FastVectorHighlighterTest, 
TestTrackingShardHandlerFactory, TestShortCircuitedRequests, 
SynonymTokenizerTest, BlockJoinFacetSimpleTest, HardAutoCommitTest, 
CdcrVersionReplicationTest, TestSolrCloudWithDelegationTokens, 
HighlighterMaxOffsetTest, TestJsonFacetRefinement, TestCSVResponseWriter, 
TestRTGBase, TestDeleteCollectionOnDownNodes, TestHdfsCloudBackupRestore, 
TestChildDocTransformer, TestSolrConfigHandlerCloud, SpatialFilterTest, 
PeerSyncWithIndexFingerprintCachingTest, TestCloudJSONFacetJoinDomain, 
TestImplicitCoreProperties, TestSerializedLuceneMatchVersion, 
MissingSegmentRecoveryTest, HdfsBasicDistributedZk2Test, 
TestSubQueryTransformerCrossCore, SolrPluginUtilsTest, TestRandomFlRTGCloud, 
ConnectionReuseTest, ImplicitSnitchTest, TestMultiWordSynonyms, 
DistributedQueryElevationComponentTest, DirectoryFactoryTest, 
CdcrReplicationHandlerTest, ConfigureRecoveryStrategyTest, CdcrUpdateLogTest, 
TriLevelCompositeIdRoutingTest, ResponseHeaderTest, TestExactSharedStatsCache, 
DistributedFacetExistsSmallTest, TestPayloadScoreQParserPlugin, 
RAMDirectoryFactoryTest, TestIBSimilarityFactory, TestPhraseSuggestions, 
HdfsRestartWhileUpdatingTest, PrimitiveFieldTypeTest, TestStressLucene, 
TestSchemaManager, TestStressReorder, CursorPagingTest, 
TestFieldCacheVsDocValues, PreAnalyzedFieldManagedSchemaCloudTest, 
OverseerCollectionConfigSetProcessorTest, TestSolrDeletionPolicy1, 
TestPartialUpdateDeduplication, TestTestInjection, PolyFieldTest, 
TestNamedUpdateProcessors, ParsingFieldUpdateProcessorsTest, 
DistributedSpellCheckComponentTest, DistributedFacetPivotLongTailTest, 
TestFunctionQuery, RecoveryZkTest, TestBinaryResponseWriter, 
TestGroupingSearch, TestUpdate, TestStressInPlaceUpdates, TestBlobHandler, 
ClassificationUpdateProcessorFactoryTest, JSONWriterTest, SimpleFacetsTest, 
TestBinaryField, SSLMigrationTest, CollectionReloadTest, 
AtomicUpdateProcessorFactoryTest, TestFieldCache, TestSolrXml, 
SchemaVersionSpecificBehaviorTest, XmlUpdateRequestHandlerTest, 
CollectionTooManyReplicasTest, TestReplicationHandler, 
TestRandomCollapseQParserPlugin, TestGraphMLResponseWriter, 
TestBM25SimilarityFactory, RecoveryAfterSoftCommitTest, 
TestHttpShardHandlerFactory, SuggestComponentContextFilterQueryTest, 
NumericFieldsTest, TestConfigsApi, InfoHandlerTest, TestDFISimilarityFactory, 
SimpleMLTQParserTest, BufferStoreTest, DeleteInactiveReplicaTest, 
TestHighlightDedupGrouping, DocValuesTest, TestXmlQParser, TestSort, 
BigEndianAscendingWordSerializerTest, TestLocalFSCloudBackupRestore, 
DistributedFacetPivotSmallTest, TestClusterProperties, MergeStrategyTest, 
TestCollationFieldDocValues, TestQuerySenderNoQuery, 
DistribJoinFromCollectionTest, TestWordDelimiterFilterFactory, 
TestHdfsBackupRestoreCore, TestLFUCache, TestSolrFieldCacheBean, 
CleanupOldIndexTest, TestStressUserVersions, TestNumericRangeQuery64, 
SpellingQueryConverterTest, SegmentsInfoRequestHandlerTest, 
HdfsCollectionsAPIDistributedZkTest, AlternateDirectoryTest, 
TestDistributedSearch, CurrencyFieldXmlFileTest, DocValuesMultiTest, 
MultiThreadedOCPTest, RestartWhileUpdatingTest, MetricsConfigTest, SampleTest, 
TestPseudoReturnFields, SearchHandlerTest, TestFieldCacheSort, 
TestLegacyNumericUtils, TestInitParams, ZkControllerTest, 
BlockJoinFacetDistribTest, DocExpirationUpdateProcessorFactoryTest, 
TestGraphTermsQParserPlugin, TestJmxIntegration, TestRawTransformer, 
TestConfigSets, TestSlowCompositeReaderWrapper, 
UUIDUpdateProcessorFallbackTest, TestRebalanceLeaders, TestCollationField, 
RequiredFieldsTest, TestFaceting, SolrCLIZkUtilsTest, OverseerTest, 
PathHierarchyTokenizerFactoryTest, TestSQLHandler, TestXIncludeConfig, 
InfixSuggestersTest, TestStressVersions, TestPushWriter, ZkCLITest, 
TestCharFilters, SolrIndexConfigTest, SpellCheckCollatorWithCollapseTest, 
TestSweetSpotSimilarityFactory, TestAuthorizationFramework, 
SolrRequestParserTest, TestTolerantSearch, TestCloudDeleteByQuery, 
TestSolr4Spatial2, TestPerFieldSimilarityWithDefaultOverride, PeerSyncTest, 
BinaryUpdateRequestHandlerTest, BooleanFieldTest, EnumFieldTest, 
TestEmbeddedSolrServerSchemaAPI, PropertiesRequestHandlerTest, 
SolrMetricsIntegrationTest, TestTlogReplica, MoreLikeThisHandlerTest, 
TestRequestForwarding, TestLegacyField, TestManagedResource, 
UniqFieldsUpdateProcessorFactoryTest, TestReRankQParserPlugin, 
UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, 
LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, 
TestRandomDVFaceting, TestRecovery, TestRealTimeGet, TestJoin, 
TestCoreContainer, SolrCmdDistributorTest, BadIndexSchemaTest, 
BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig, 
TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, QueryParsingTest, 
CSVRequestHandlerTest, TestComponentsName, HighlighterConfigTest, SOLR749Test, 
TestQuerySenderListener, UpdateParamsTest, TestSolrIndexConfig, 
BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, 
MultiTermTest, TestDocSet, TestSearchPerf, TestConfig, 
DirectSolrConnectionTest, NotRequiredUniqueKeyTest, EchoParamsTest, 
TimeZoneUtilsTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, 
DateMathParserTest, TestSystemIdResolver, PrimUtilsTest, 
TestSuggestSpellingConverter, DateFieldTest, ClusterStateTest, TestSolrJ, 
TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, FileUtilsTest, 
CircularListTest, DistributedMLTComponentTest, SolrTestCaseJ4Test, 
TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, 
TestEmbeddedSolrServerConstructors, AliasIntegrationTest, AssignTest, 
AsyncCallRequestStatusResponseTest, BaseCdcrDistributedZkTest, 
CdcrBootstrapTest, ChaosMonkeySafeLeaderWithPullReplicasTest, 
CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, 
CreateCollectionCleanupTest, CustomCollectionTest, DistribCursorPagingTest, 
DistributedQueueTest, DistributedVersionInfoTest, LeaderElectionContextKeyTest, 
LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnShardRestartTest, 
MigrateRouteKeyTest, MoveReplicaTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverriddenZkACLAndCredentialsProvidersTest, OverseerModifyCollectionTest, 
OverseerRolesTest, OverseerStatusTest, OverseerTaskQueueTest, 
PeerSyncReplicationTest, RemoteQueryErrorTest, ReplicationFactorTest, 
SaslZkACLProviderTest, ShardSplitTest, TestPullReplica, TestReplicaProperties, 
TestSSLRandomization, TestSolrCloudWithSecureImpersonation, 
TestTolerantUpdateProcessorCloud, TlogReplayBufferedWhileIndexingTest, 
AutoScalingHandlerTest, HdfsBasicDistributedZkTest, 
HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, 
HdfsRecoverLeaseTest, HdfsRecoveryZkTest, HdfsThreadLeakTest, 
HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, 
HdfsWriteToMultipleCollectionsTest, StressHdfsTest, ZkStateReaderTest, 
ZkStateWriterTest, RulesTest, TestConfigSetProperties, TestCustomStream, 
TestNRTOpen, TestReloadAndDeleteDocs, TestSimpleTextCodec, 
TestSolrConfigHandler]
   [junit4] Completed [575/725 (2!)] on J0 in 19.77s, 4 tests, 1 failure <<< 
FAILURES!

[...truncated 43885 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to