You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/03/13 21:04:25 UTC
[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_172) - Build #
7776 - Still Unstable!
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...]