[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_172) - Build # 7776 - Still Unstable!

Policeman Jenkins Server Wed, 13 Mar 2019 14:08:51 -0700

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7776/
Java: 32bit/jdk1.8.0_172 -client -XX:+UseParallelGC


1 tests failed.
FAILED:  org.apache.solr.handler.TestSolrConfigHandlerCloud.test

Error Message:
Could not get expected value  'P val' for path 'response/params/y/p' full 
output: {   "responseHeader":{     "status":0,     "QTime":0},   "response":{   
  "znodeVersion":0,     "params":{"x":{         "a":"A val",         "b":"B 
val",         "":{"v":0}}}}},  from server:  
http://127.0.0.1:62912/collection1_shard2_replica_n2

Stack Trace:
java.lang.AssertionError: Could not get expected value  'P val' for path 
'response/params/y/p' full output: {
  "responseHeader":{
    "status":0,
    "QTime":0},
  "response":{
    "znodeVersion":0,
    "params":{"x":{
        "a":"A val",
        "b":"B val",
        "":{"v":0}}}}},  from server:  
http://127.0.0.1:62912/collection1_shard2_replica_n2
        at 
__randomizedtesting.SeedInfo.seed([959A6DB46D0DE08A:1DCE526EC3F18D72]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:590)
        at 
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:244)
        at 
org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:51)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13456 lines...]
   [junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerCloud
   [junit4]   2> 1736985 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[959A6DB46D0DE08A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\init-core-data-001
   [junit4]   2> 1736988 WARN  
(SUITE-TestSolrConfigHandlerCloud-seed#[959A6DB46D0DE08A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1736989 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[959A6DB46D0DE08A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1736997 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[959A6DB46D0DE08A]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1736997 INFO  
(SUITE-TestSolrConfigHandlerCloud-seed#[959A6DB46D0DE08A]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1737004 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1737004 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1737005 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1737105 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:62863
   [junit4]   2> 1737105 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62863
   [junit4]   2> 1737105 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 62863
   [junit4]   2> 1737113 INFO  (zkConnectionManagerCallback-6344-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1737120 INFO  (zkConnectionManagerCallback-6346-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1737123 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 1737126 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 1737129 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 1737131 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 1737133 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 1737136 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 1737137 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 1737139 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 1737141 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 1737144 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 1737146 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer put 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 1737149 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly 
asked otherwise
   [junit4]   2> 1737863 WARN  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1737864 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1737864 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1737864 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
   [junit4]   2> 1737865 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1737865 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1737865 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1737866 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1beb64f{/,null,AVAILABLE}
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@def4d3{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:62871}
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.s.Server Started @1737915ms
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\tempDir-001/control/data,
 hostContext=/, hostPort=62871, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\control-001\cores}
   [junit4]   2> 1737867 ERROR 
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1737867 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-13T19:11:19.773Z
   [junit4]   2> 1737879 INFO  (zkConnectionManagerCallback-6348-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1737881 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1737881 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\control-001\solr.xml
   [junit4]   2> 1737900 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1737900 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1737904 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1738541 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1738544 WARN  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@d89f14[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1738555 WARN  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1b23d26[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1738556 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62863/solr
   [junit4]   2> 1738562 INFO  (zkConnectionManagerCallback-6355-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1738567 INFO  (zkConnectionManagerCallback-6357-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1738711 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:62871_
   [junit4]   2> 1738712 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.c.Overseer Overseer 
(id=72058024508063748-127.0.0.1:62871_-n_0000000000) starting
   [junit4]   2> 1738730 INFO  (zkConnectionManagerCallback-6364-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1738734 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:62863/solr ready
   [junit4]   2> 1738734 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62871_
   [junit4]   2> 1738735 INFO  
(OverseerStateUpdate-72058024508063748-127.0.0.1:62871_-n_0000000000) 
[n:127.0.0.1:62871_    ] o.a.s.c.Overseer Starting to work on the main queue : 
127.0.0.1:62871_
   [junit4]   2> 1738735 INFO  
(OverseerStateUpdate-72058024508063748-127.0.0.1:62871_-n_0000000000) 
[n:127.0.0.1:62871_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1738741 INFO  (zkCallback-6363-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1738809 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, 
keeping metrics history in memory.
   [junit4]   2> 1738849 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1738863 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1738864 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1738864 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) 
[n:127.0.0.1:62871_    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions 
underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\control-001\cores
   [junit4]   2> 1738907 INFO  (zkConnectionManagerCallback-6370-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1738909 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1738911 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62863/solr ready
   [junit4]   2> 1738913 INFO  (qtp16136882-18669) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:62871_&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1738918 INFO  
(OverseerThreadFactory-5595-thread-1-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
control_collection
   [junit4]   2> 1739030 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_    
x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core 
create command 
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1739030 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_    
x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault 
Allocating transient cache for 4 transient cores
   [junit4]   2> 1740081 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1740129 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
   [junit4]   2> 1740263 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1740300 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' 
using configuration from collection control_collection, trusted=true
   [junit4]   2> 1740301 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.control_collection.shard1.replica_n1' (registry 
'solr.core.control_collection.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1740301 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore 
at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\control-001\cores\control_collection_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\control-001\cores\control_collection_shard1_replica_n1\data\]
   [junit4]   2> 1740304 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=30, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=65.3134765625, 
floorSegmentMB=0.9951171875, forceMergeDeletesPctAllowed=9.597032707350177, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=25.675133075922506
   [junit4]   2> 1740307 WARN  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 1740408 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1740408 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1740413 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1740413 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1740414 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1972916041, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1740415 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@15f4ae9[control_collection_shard1_replica_n1] main]
   [junit4]   2> 1740418 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1740418 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1740419 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1740419 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1627918730343219200
   [junit4]   2> 1740426 INFO  
(searcherExecutor-5600-thread-1-processing-n:127.0.0.1:62871_ 
x:control_collection_shard1_replica_n1 c:control_collection s:shard1) 
[n:127.0.0.1:62871_ c:control_collection s:shard1  
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[control_collection_shard1_replica_n1] Registered new searcher 
Searcher@15f4ae9[control_collection_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1740429 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, 
version=0}
   [junit4]   2> 1740429 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/control_collection/leaders/shard1
   [junit4]   2> 1740434 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1740435 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1740435 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:62871/control_collection_shard1_replica_n1/
   [junit4]   2> 1740435 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 1740435 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
http://127.0.0.1:62871/control_collection_shard1_replica_n1/ has no replicas
   [junit4]   2> 1740435 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/control_collection/leaders/shard1/leader after winning as 
/collections/control_collection/leader_elect/shard1/election/72058024508063748-core_node2-n_0000000000
   [junit4]   2> 1740438 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62871/control_collection_shard1_replica_n1/ shard1
   [junit4]   2> 1740540 INFO  (zkCallback-6356-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1740542 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1740545 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:control_collection s:shard1  x:control_collection_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1515
   [junit4]   2> 1740550 INFO  (qtp16136882-18669) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
45 seconds. Check all shard replicas
   [junit4]   2> 1740646 INFO  (zkCallback-6356-thread-3) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1740646 INFO  (zkCallback-6356-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/control_collection/state.json] for collection 
[control_collection] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 1740647 INFO  (qtp16136882-18669) [n:127.0.0.1:62871_    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:62871_&wt=javabin&version=2}
 status=0 QTime=1734
   [junit4]   2> 1740659 INFO  (zkConnectionManagerCallback-6376-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1740661 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1740663 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62863/solr ready
   [junit4]   2> 1740663 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 1740666 INFO  (qtp16136882-18668) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1740671 INFO  
(OverseerThreadFactory-5595-thread-2-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 1740671 INFO  
(OverseerCollectionConfigSetProcessor-72058024508063748-127.0.0.1:62871_-n_0000000000)
 [n:127.0.0.1:62871_    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1740874 WARN  
(OverseerThreadFactory-5595-thread-2-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_    ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to 
create a collection (collection1) without cores.
   [junit4]   2> 1740877 INFO  (qtp16136882-18668) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
45 seconds. Check all shard replicas
   [junit4]   2> 1740879 INFO  (qtp16136882-18668) [n:127.0.0.1:62871_    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2}
 status=0 QTime=213
   [junit4]   2> 1740881 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances 
pullReplicaCount=0 numOtherReplicas=4
   [junit4]   2> 1741475 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-1-001
 of type NRT
   [junit4]   2> 1741479 WARN  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1741479 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1741479 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1741479 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
   [junit4]   2> 1741480 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1741480 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1741480 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1741480 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@8154c3{/,null,AVAILABLE}
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1c6bca5{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:62912}
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.Server Started @1741536ms
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\tempDir-001/jetty1,
 replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=62912, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-1-001\cores}
   [junit4]   2> 1741487 ERROR (closeThreadPool-6377-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1741487 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-13T19:11:23.393Z
   [junit4]   2> 1741494 INFO  (zkConnectionManagerCallback-6379-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1741495 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1741495 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-1-001\solr.xml
   [junit4]   2> 1741505 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1741505 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1741509 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1741990 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1741995 WARN  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1bda537[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1742003 WARN  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@15776cb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1742005 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62863/solr
   [junit4]   2> 1742008 INFO  (zkConnectionManagerCallback-6386-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742010 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x10000643a050009, likely client has closed socket
   [junit4]   2> 1742012 INFO  (zkConnectionManagerCallback-6388-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742019 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1742024 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.ZkController Publish node=127.0.0.1:62912_ as 
DOWN
   [junit4]   2> 1742024 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 4 transient cores
   [junit4]   2> 1742024 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62912_
   [junit4]   2> 1742024 INFO  (zkCallback-6375-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1742024 INFO  (zkCallback-6356-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1742024 INFO  (zkCallback-6363-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1742029 INFO  (zkCallback-6387-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1742067 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-2-001
 of type NRT
   [junit4]   2> 1742068 WARN  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1742069 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1742069 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1742069 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
   [junit4]   2> 1742070 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1742070 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1742071 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1742071 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4a12a7{/,null,AVAILABLE}
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@13c4cc9{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:62928}
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.Server Started @1742122ms
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\tempDir-001/jetty2,
 replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=62928, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-2-001\cores}
   [junit4]   2> 1742074 ERROR (closeThreadPool-6377-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1742074 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-13T19:11:23.980Z
   [junit4]   2> 1742085 INFO  (zkConnectionManagerCallback-6392-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742085 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1742085 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-2-001\solr.xml
   [junit4]   2> 1742101 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1742101 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1742103 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1742107 INFO  (zkConnectionManagerCallback-6397-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742109 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1742110 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:62863/solr ready
   [junit4]   2> 1742111 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, 
keeping metrics history in memory.
   [junit4]   2> 1742185 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1742210 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1742210 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1742213 INFO  (closeThreadPool-6377-thread-1) 
[n:127.0.0.1:62912_    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions 
underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-1-001\cores
   [junit4]   2> 1742695 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-3-001
 of type NRT
   [junit4]   2> 1742695 WARN  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1742695 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1742695 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1742695 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
   [junit4]   2> 1742695 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1742695 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1742696 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1742696 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@26f09e{/,null,AVAILABLE}
   [junit4]   2> 1742718 INFO  
(OverseerCollectionConfigSetProcessor-72058024508063748-127.0.0.1:62871_-n_0000000000)
 [n:127.0.0.1:62871_    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1742725 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@7704e2{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:62937}
   [junit4]   2> 1742725 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.s.Server Started @1742777ms
   [junit4]   2> 1742725 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\tempDir-001/jetty3,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=62937, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-3-001\cores}
   [junit4]   2> 1742729 ERROR (closeThreadPool-6377-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1742729 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1742729 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1742729 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1742729 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1742729 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-13T19:11:24.635Z
   [junit4]   2> 1742750 INFO  (zkConnectionManagerCallback-6401-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742752 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1742753 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-3-001\solr.xml
   [junit4]   2> 1742753 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x10000643a05000d, likely client has closed socket
   [junit4]   2> 1742763 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1742763 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1742765 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1742771 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1742779 WARN  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@840101[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1742795 WARN  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1a86c2b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1742795 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62863/solr
   [junit4]   2> 1742825 INFO  (zkConnectionManagerCallback-6408-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742834 INFO  (zkConnectionManagerCallback-6410-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742845 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1742853 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.ZkController Publish node=127.0.0.1:62928_ as 
DOWN
   [junit4]   2> 1742856 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 4 transient cores
   [junit4]   2> 1742856 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62928_
   [junit4]   2> 1742864 INFO  (zkCallback-6387-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1742864 INFO  (zkCallback-6375-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1742864 INFO  (zkCallback-6356-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1742864 INFO  (zkCallback-6363-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1742937 INFO  (zkCallback-6396-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1742941 INFO  (zkCallback-6409-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1742944 INFO  (zkConnectionManagerCallback-6417-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1742945 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1742946 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:62863/solr ready
   [junit4]   2> 1742946 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, 
keeping metrics history in memory.
   [junit4]   2> 1742993 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1743005 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1743005 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1743009 INFO  (closeThreadPool-6377-thread-2) 
[n:127.0.0.1:62928_    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions 
underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-2-001\cores
   [junit4]   2> 1743245 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-4-001
 of type NRT
   [junit4]   2> 1743245 WARN  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 1743245 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 1743245 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 1743245 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
   [junit4]   2> 1743249 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1743249 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1743249 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1743249 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@fc55c8{/,null,AVAILABLE}
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@ca70c7{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:62956}
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.s.Server Started @1743299ms
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\tempDir-001/jetty4,
 solrconfig=solrconfig.xml, hostContext=/, hostPort=62956, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-4-001\cores}
   [junit4]   2> 1743250 ERROR (closeThreadPool-6377-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
9.0.0
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1743250 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-03-13T19:11:25.156Z
   [junit4]   2> 1743254 INFO  (zkConnectionManagerCallback-6420-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1743255 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 1743255 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-4-001\solr.xml
   [junit4]   2> 1743267 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1743267 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1743267 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1743864 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1743876 WARN  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@47223a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1743886 WARN  (closeThreadPool-6377-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@165d8a6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1743887 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62863/solr
   [junit4]   2> 1743890 INFO  (zkConnectionManagerCallback-6427-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1743891 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x10000643a050012, likely client has closed socket
   [junit4]   2> 1743894 INFO  (zkConnectionManagerCallback-6429-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1743901 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 1743905 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.ZkController Publish node=127.0.0.1:62937_ as DOWN
   [junit4]   2> 1743907 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 1743907 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62937_
   [junit4]   2> 1743910 INFO  (zkCallback-6387-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6363-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6375-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6356-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6409-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6396-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6416-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743910 INFO  (zkCallback-6428-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1743999 INFO  (zkConnectionManagerCallback-6436-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1744001 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1744004 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62863/solr ready
   [junit4]   2> 1744005 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1744046 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1744058 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1744058 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1744060 INFO  (closeThreadPool-6377-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-3-001\cores
   [junit4]   2> 1744530 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1744536 WARN  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1d674d0[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1744542 WARN  (closeThreadPool-6377-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@c9c4b7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1744542 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62863/solr
   [junit4]   2> 1744547 INFO  (zkConnectionManagerCallback-6443-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1744553 INFO  (zkConnectionManagerCallback-6445-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1744556 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1744561 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.ZkController Publish node=127.0.0.1:62956_ as DOWN
   [junit4]   2> 1744563 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 1744563 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:62956_
   [junit4]   2> 1744569 INFO  (zkCallback-6387-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6416-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6396-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6363-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6375-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6428-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6356-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744569 INFO  (zkCallback-6409-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744641 INFO  (zkCallback-6435-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744641 INFO  (zkCallback-6444-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 1744644 INFO  (zkConnectionManagerCallback-6452-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1744646 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 1744646 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62863/solr ready
   [junit4]   2> 1744646 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1744681 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1744693 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1744693 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1744696 INFO  (closeThreadPool-6377-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-4-001\cores
   [junit4]   2> 1744786 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params 
node=127.0.0.1:62928_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1744786 INFO  (qtp16136882-18669) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params 
node=127.0.0.1:62912_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1744789 INFO  (qtp16136882-18673) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params 
node=127.0.0.1:62937_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1744792 INFO  (qtp16136882-18668) [n:127.0.0.1:62871_    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params 
node=127.0.0.1:62956_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1744795 INFO  
(OverseerThreadFactory-5595-thread-3-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_ c:collection1 s:shard1  ] o.a.s.c.a.c.AddReplicaCmd Node 
Identified 127.0.0.1:62928_ for creating new replica of shard shard1 for 
collection collection1
   [junit4]   2> 1744795 INFO  
(OverseerThreadFactory-5595-thread-4-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_ c:collection1 s:shard2  ] o.a.s.c.a.c.AddReplicaCmd Node 
Identified 127.0.0.1:62912_ for creating new replica of shard shard2 for 
collection collection1
   [junit4]   2> 1744795 INFO  
(OverseerThreadFactory-5595-thread-3-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_ c:collection1 s:shard1  ] o.a.s.c.a.c.AddReplicaCmd 
Returning CreateReplica command.
   [junit4]   2> 1744795 INFO  
(OverseerThreadFactory-5595-thread-4-processing-n:127.0.0.1:62871_) 
[n:127.0.0.1:62871_ c:collection1 s:shard2  ] o.a.s.c.a.c.AddReplicaCmd 
Returning CreateReplica command.
   [junit4]   2> 1744801 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_    
x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1744813 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_    
x:collection1_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1745852 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1745852 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 1745876 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema 
[collection1_shard2_replica_n2] Schema name=test
   [junit4]   2> 1745884 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema 
[collection1_shard1_replica_n1] Schema name=test
   [junit4]   2> 1746040 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.s.IndexSchema 
Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1746046 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema 
Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1746077 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard2_replica_n2' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 1746077 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard2.replica_n2' (registry 
'solr.core.collection1.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1746077 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.SolrCore 
[[collection1_shard2_replica_n2] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-1-001\cores\collection1_shard2_replica_n2],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-1-001\cores\collection1_shard2_replica_n2\data\]
   [junit4]   2> 1746080 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=30, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=65.3134765625, 
floorSegmentMB=0.9951171875, forceMergeDeletesPctAllowed=9.597032707350177, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=25.675133075922506
   [junit4]   2> 1746082 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1_shard1_replica_n1' using configuration from 
collection collection1, trusted=true
   [junit4]   2> 1746082 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1.shard1.replica_n1' (registry 
'solr.core.collection1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1f3ebdd
   [junit4]   2> 1746083 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[[collection1_shard1_replica_n1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-2-001\cores\collection1_shard1_replica_n1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001\shard-2-001\cores\collection1_shard1_replica_n1\data\]
   [junit4]   2> 1746083 WARN  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
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> 1746085 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=30, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=65.3134765625, 
floorSegmentMB=0.9951171875, forceMergeDeletesPctAllowed=9.597032707350177, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=25.675133075922506
   [junit4]   2> 1746089 WARN  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 1746197 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1746197 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1746201 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 1746201 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 1746201 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1972916041, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1746201 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1b9a4a4[collection1_shard1_replica_n1] main]
   [junit4]   2> 1746204 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1746204 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1746205 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1746205 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1746205 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1746205 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1627918736410279936
   [junit4]   2> 1746205 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 1746205 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 1746211 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1972916041, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1746213 INFO  
(searcherExecutor-5642-thread-1-processing-n:127.0.0.1:62928_ 
x:collection1_shard1_replica_n1 c:collection1 s:shard1) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@1b9a4a4[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1746214 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1fde337[collection1_shard2_replica_n2] main]
   [junit4]   2> 1746215 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms 
Successful update of terms at /collections/collection1/terms/shard1 to 
Terms{values={core_node3=0}, version=0}
   [junit4]   2> 1746215 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/collection1/leaders/shard1
   [junit4]   2> 1746219 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 1746219 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 1746219 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1746219 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1627918736424960000
   [junit4]   2> 1746220 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1746220 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1746220 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:62928/collection1_shard1_replica_n1/
   [junit4]   2> 1746220 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 1746220 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy 
http://127.0.0.1:62928/collection1_shard1_replica_n1/ has no replicas
   [junit4]   2> 1746220 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/collection1/leaders/shard1/leader after winning as 
/collections/collection1/leader_elect/shard1/election/72058024508063759-core_node3-n_0000000000
   [junit4]   2> 1746221 INFO  
(searcherExecutor-5641-thread-1-processing-n:127.0.0.1:62912_ 
x:collection1_shard2_replica_n2 c:collection1 s:shard2) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.SolrCore 
[collection1_shard2_replica_n2] Registered new searcher 
Searcher@1fde337[collection1_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1746221 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62928/collection1_shard1_replica_n1/ shard1
   [junit4]   2> 1746228 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.ZkShardTerms 
Successful update of terms at /collections/collection1/terms/shard2 to 
Terms{values={core_node4=0}, version=0}
   [junit4]   2> 1746228 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/collection1/leaders/shard2
   [junit4]   2> 1746232 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1746232 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1746232 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:62912/collection1_shard2_replica_n2/
   [junit4]   2> 1746232 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 1746232 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.SyncStrategy 
http://127.0.0.1:62912/collection1_shard2_replica_n2/ has no replicas
   [junit4]   2> 1746232 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/collection1/leaders/shard2/leader after winning as 
/collections/collection1/leader_elect/shard2/election/72058024508063754-core_node4-n_0000000000
   [junit4]   2> 1746233 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:62912/collection1_shard2_replica_n2/ shard2
   [junit4]   2> 1746339 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 1746340 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 1746346 INFO  (qtp25475344-18732) [n:127.0.0.1:62912_ 
c:collection1 s:shard2  x:collection1_shard2_replica_n2] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1534
   [junit4]   2> 1746346 INFO  (qtp30918982-18758) [n:127.0.0.1:62928_ 
c:collection1 s:shard1  x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1546
   [junit4]   2> 1746374 INFO  (qtp16136882-18671) [n:127.0.0.1:62871_ 
c:collection1   ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={node=127.0.0.1:62928_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2}
 status=0 QTime=1588
   [junit4]   2> 1746374 INFO  (qtp16136882-18669) [n:127.0.0.1:62871_ 
c:collection1   ] o.a.s.s.HttpSolrCall [admin] webapp=null 
path=/admin/collections 
params={node=127.0.0.1:62912_&action=ADDREPLICA&collection=collection1

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

 INFO  (zkCallback-6444-thread-2) [    ] o.a.s.c.OverseerElectionContext I am 
going to be the leader 127.0.0.1:62956_
   [junit4]   2> 1794177 INFO  (zkCallback-6451-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1794177 INFO  (closeThreadPool-7163-thread-10) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1794177 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x10000643a050010, likely client has closed socket
   [junit4]   2> 1794177 INFO  (closeThreadPool-7163-thread-10) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@d6a54f: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@1a9369a
   [junit4]   2> 1794177 INFO  (closeThreadPool-7163-thread-2) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1794177 INFO  (closeThreadPool-7163-thread-10) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1794182 INFO  (closeThreadPool-7163-thread-8) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@13c4cc9{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 1794185 INFO  (closeThreadPool-7163-thread-8) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4a12a7{/,null,UNAVAILABLE}
   [junit4]   2> 1794186 INFO  (zkCallback-6451-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 1794186 INFO  (closeThreadPool-7163-thread-8) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1794194 INFO  (closeThreadPool-7163-thread-10) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@ca70c7{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 1794196 INFO  (closeThreadPool-7163-thread-10) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@fc55c8{/,null,UNAVAILABLE}
   [junit4]   2> 1794198 INFO  (closeThreadPool-7163-thread-10) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 1794199 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1794202 WARN  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        135     /solr/configs/conf1
   [junit4]   2>        17      /solr/collections/collection1/state.json
   [junit4]   2>        12      /solr/aliases.json
   [junit4]   2>        5       /solr/security.json
   [junit4]   2>        5       /solr/collections/collection1/terms/shard1
   [junit4]   2>        5       /solr/collections/collection1/terms/shard2
   [junit4]   2>        2       
/solr/collections/control_collection/terms/shard1
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        12      /solr/clusterstate.json
   [junit4]   2>        12      /solr/clusterprops.json
   [junit4]   2>        6       /solr/collections/control_collection/state.json
   [junit4]   2>        2       
/solr/overseer_elect/election/72058024508063748-127.0.0.1:62871_-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/72058024508063759-127.0.0.1:62928_-n_0000000002
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        12      /solr/live_nodes
   [junit4]   2>        12      /solr/collections
   [junit4]   2> 
   [junit4]   2> 1794210 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62863
   [junit4]   2> 1794210 INFO  
(TEST-TestSolrConfigHandlerCloud.test-seed#[959A6DB46D0DE08A]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 62863
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=test 
-Dtests.seed=959A6DB46D0DE08A -Dtests.slow=true -Dtests.locale=sr 
-Dtests.timezone=Etc/GMT-1 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 58.2s J0 | TestSolrConfigHandlerCloud.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Could not get expected 
value  'P val' for path 'response/params/y/p' full output: {
   [junit4]    >   "responseHeader":{
   [junit4]    >     "status":0,
   [junit4]    >     "QTime":0},
   [junit4]    >   "response":{
   [junit4]    >     "znodeVersion":0,
   [junit4]    >     "params":{"x":{
   [junit4]    >         "a":"A val",
   [junit4]    >         "b":"B val",
   [junit4]    >         "":{"v":0}}}}},  from server:  
http://127.0.0.1:62912/collection1_shard2_replica_n2
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([959A6DB46D0DE08A:1DCE526EC3F18D72]:0)
   [junit4]    >        at 
org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:590)
   [junit4]    >        at 
org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:244)
   [junit4]    >        at 
org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:51)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestSolrConfigHandlerCloud_959A6DB46D0DE08A-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): {}, 
docValues:{}, maxPointsInLeafNode=1640, maxMBSortInHeap=6.83165385085677, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@12f2940),
 locale=sr, timezone=Etc/GMT-1
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_172 
(32-bit)/cpus=3,threads=1,free=298195080,total=531103744
   [junit4]   2> NOTE: All tests run in this JVM: [SliceStateTest, 
TestNumericRangeQuery32, PreAnalyzedFieldTest, HttpPartitionOnCommitTest, 
PolyFieldTest, FullHLLTest, TestTolerantUpdateProcessorRandomCloud, 
TestRequestForwarding, TestCryptoKeys, TestPseudoReturnFields, 
CurrencyRangeFacetCloudTest, TestMissingGroups, 
TestSolrCloudWithHadoopAuthPlugin, TestLegacyBM25SimilarityFactory, 
BlockJoinFacetSimpleTest, TestDFRSimilarityFactory, 
TestMiniSolrCloudClusterSSL, UpdateParamsTest, 
LeaderFailoverAfterPartitionTest, DistributedQueryComponentCustomSortTest, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestTrie, 
ShufflingReplicaListTransformerTest, TestDynamicURP, TestFreeTextSuggestions, 
TestSuggestSpellingConverter, FileBasedSpellCheckerTest, TestFaceting, 
SolrShardReporterTest, TestCloudPhrasesIdentificationComponent, 
TestCorePropertiesReload, TestSchemaManager, TestSolrCloudSnapshots, 
OverseerCollectionConfigSetProcessorTest, ZkStateWriterTest, 
TestFieldTypeCollectionResource, V2StandaloneTest, 
TestManagedSynonymGraphFilterFactory, TestBinaryResponseWriter, TestUtils, 
OrderedExecutorTest, FacetPivotSmallTest, InfoHandlerTest, NumericFieldsTest, 
RequiredFieldsTest, DefaultValueUpdateProcessorTest, 
TestAuthorizationFramework, TestJmxIntegration, 
SchemaVersionSpecificBehaviorTest, ManagedSchemaRoundRobinCloudTest, 
TestCloudNestedDocsSort, ConnectionReuseTest, TestMacroExpander, 
AutoScalingHandlerTest, ChangedSchemaMergeTest, TestDelegationWithHadoopAuth, 
TestSolrDeletionPolicy1, UninvertDocValuesMergePolicyTest, SpatialFilterTest, 
TestPullReplicaErrorHandling, TestReRankQParserPlugin, 
TestManagedResourceStorage, TestCustomSort, RandomizedTaggerTest, 
TestHighFrequencyDictionaryFactory, LeaderElectionIntegrationTest, 
ProtectedTermFilterFactoryTest, DocExpirationUpdateProcessorFactoryTest, 
ShardSplitTest, TestRemoteStreaming, TimeRoutedAliasUpdateProcessorTest, 
TestQuerySenderListener, TestHttpShardHandlerFactory, 
SpellPossibilityIteratorTest, TestConfigSetProperties, SolrInfoBeanTest, 
TestManagedSchemaAPI, SuggesterTest, TestSimComputePlanAction, 
TestDeprecatedFilters, MigrateRouteKeyTest, DebugComponentTest, 
TestCollectionAPI, ShardRoutingTest, DistanceFunctionTest, 
TestTrackingShardHandlerFactory, TestSearchPerf, ExitableDirectoryReaderTest, 
TaggerTest, TestLMDirichletSimilarityFactory, TestNamedUpdateProcessors, 
ZkCLITest, TestClusterProperties, TestInPlaceUpdatesDistrib, 
TestScoreJoinQPNoScore, CollectionPropsTest, TestSolrCoreParser, 
DateRangeFieldTest, CircularListTest, 
ChaosMonkeySafeLeaderWithPullReplicasTest, TestCrossCoreJoin, 
AnalysisErrorHandlingTest, TestBulkSchemaAPI, TestLegacyTerms, CoreSorterTest, 
TestRandomCollapseQParserPlugin, TestSolrConfigHandlerCloud]
   [junit4] Completed [261/847 (1!)] on J0 in 58.49s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 47381 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7776/consoleText

[repro] Revision: d8f2a02fdb11a484425f9fddfa7061711d2f0034

[repro] Ant options: "-Dargs=-client -XX:+UseParallelGC"

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr\core
[repro]       TestSolrConfigHandlerCloud
[repro] ant compile-test

[...truncated 3587 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestSolrConfigHandlerCloud" -Dtests.showOutput=onerror 
"-Dargs=-client -XX:+UseParallelGC" -Dtests.seed=959A6DB46D0DE08A 
-Dtests.slow=true -Dtests.locale=sr -Dtests.timezone=Etc/GMT-1 
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1

[...truncated 70 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.handler.TestSolrConfigHandlerCloud
[repro] Exiting with code 0

[...truncated 78 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_172) - Build # 7776 - Still Unstable!

Reply via email to