Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-8.x/105/

1 tests failed.
FAILED:  
org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution

Error Message:
0.8270447626785014 0.8286288686448905

Stack Trace:
java.lang.AssertionError: 0.8270447626785014 0.8286288686448905
        at 
__randomizedtesting.SeedInfo.seed([E3F8F49E99795575:DE82DF30BA01FF62]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution(MathExpressionTest.java:4590)
        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 
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 16653 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/init-core-data-001
   [junit4]   2> 187581 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 187583 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 187583 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 187584 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001
   [junit4]   2> 187585 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 187592 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 187592 INFO  (ZkTestServer Run Thread) [    ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 187692 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:34908
   [junit4]   2> 187692 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34908
   [junit4]   2> 187692 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 34908
   [junit4]   2> 187701 INFO  (zkConnectionManagerCallback-925-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187714 INFO  (zkConnectionManagerCallback-927-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187716 INFO  (zkConnectionManagerCallback-929-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187726 WARN  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 187726 WARN  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 187726 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 187727 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 187727 INFO  (jetty-launcher-930-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_191-b12
   [junit4]   2> 187727 WARN  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 187727 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 187727 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 187727 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 187727 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 187730 INFO  (jetty-launcher-930-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_191-b12
   [junit4]   2> 187731 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 187739 WARN  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 187739 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 187739 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 187739 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: 
c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
   [junit4]   2> 187742 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 187743 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 187743 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 187743 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@70f2da73{/solr,null,AVAILABLE}
   [junit4]   2> 187746 WARN  (jetty-launcher-930-thread-3) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@3d57f779[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187749 INFO  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 187749 INFO  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 187749 INFO  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 187749 INFO  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3a9acf34{/solr,null,AVAILABLE}
   [junit4]   2> 187750 WARN  (jetty-launcher-930-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@5e51aba0[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@3e158b6f{SSL,[ssl, 
http/1.1]}{127.0.0.1:39665}
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-3) [    ] 
o.e.j.s.Server Started @187827ms
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=39665}
   [junit4]   2> 187757 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@10fc2a14{/solr,null,AVAILABLE}
   [junit4]   2> 187758 ERROR (jetty-launcher-930-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 187758 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 187758 WARN  (jetty-launcher-930-thread-4) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@5f359aed[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187758 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.2.0
   [junit4]   2> 187758 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 187758 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 187758 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-05-19T09:46:08.402Z
   [junit4]   2> 187760 INFO  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1c9b6f82{SSL,[ssl, 
http/1.1]}{127.0.0.1:45733}
   [junit4]   2> 187760 INFO  (jetty-launcher-930-thread-1) [    ] 
o.e.j.s.Server Started @187830ms
   [junit4]   2> 187760 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45733}
   [junit4]   2> 187760 ERROR (jetty-launcher-930-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 187760 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 187763 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.2.0
   [junit4]   2> 187763 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 187763 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 187763 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-05-19T09:46:08.407Z
   [junit4]   2> 187765 INFO  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 187765 INFO  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 187765 INFO  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 187770 INFO  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@539f6b5{/solr,null,AVAILABLE}
   [junit4]   2> 187770 WARN  (jetty-launcher-930-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@2fd86eb2[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@43260df0{SSL,[ssl, 
http/1.1]}{127.0.0.1:35182}
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.e.j.s.Server Started @187843ms
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35182}
   [junit4]   2> 187773 ERROR (jetty-launcher-930-thread-4) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.2.0
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 187773 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 187774 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-05-19T09:46:08.417Z
   [junit4]   2> 187777 INFO  (zkConnectionManagerCallback-932-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@ac41960{SSL,[ssl, 
http/1.1]}{127.0.0.1:37266}
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.e.j.s.Server Started @187847ms
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37266}
   [junit4]   2> 187777 ERROR (jetty-launcher-930-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.2.0
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 187777 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 187778 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2019-05-19T09:46:08.421Z
   [junit4]   2> 187778 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 187778 INFO  (zkConnectionManagerCallback-936-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187784 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 187785 INFO  (zkConnectionManagerCallback-934-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187785 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 187786 INFO  (zkConnectionManagerCallback-938-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187787 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 187931 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 187931 WARN  (jetty-launcher-930-thread-3) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 187964 WARN  (jetty-launcher-930-thread-3) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1d7b98fc[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187975 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 187975 WARN  (jetty-launcher-930-thread-2) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 187976 WARN  (jetty-launcher-930-thread-3) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 187976 WARN  (jetty-launcher-930-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@42b69379[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187979 WARN  (jetty-launcher-930-thread-2) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 187980 WARN  (jetty-launcher-930-thread-3) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@3498eb06[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187981 INFO  (jetty-launcher-930-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
   [junit4]   2> 187981 WARN  (jetty-launcher-930-thread-2) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@35410a6c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 187982 INFO  (jetty-launcher-930-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
   [junit4]   2> 187985 INFO  (zkConnectionManagerCallback-954-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187991 INFO  (zkConnectionManagerCallback-951-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 187992 INFO  (zkConnectionManagerCallback-956-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188008 WARN  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 188008 INFO  (zkConnectionManagerCallback-958-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188014 WARN  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 188016 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:37266_solr
   [junit4]   2> 188017 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.Overseer Overseer 
(id=73328593821040649-127.0.0.1:37266_solr-n_0000000000) starting
   [junit4]   2> 188021 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39665_solr
   [junit4]   2> 188025 INFO  (zkCallback-957-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 188040 INFO  (zkConnectionManagerCallback-967-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188041 INFO  (zkConnectionManagerCallback-972-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188042 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 188043 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 188043 INFO  (zkCallback-955-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 188044 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:34908/solr ready
   [junit4]   2> 188048 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:34908/solr ready
   [junit4]   2> 188061 INFO  
(OverseerStateUpdate-73328593821040649-127.0.0.1:37266_solr-n_0000000000) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:37266_solr
   [junit4]   2> 188068 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:37266_solr as DOWN
   [junit4]   2> 188070 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 188070 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37266_solr
   [junit4]   2> 188074 INFO  (zkCallback-955-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 188075 INFO  (zkCallback-966-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 188085 INFO  (zkCallback-971-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 188085 INFO  (zkCallback-957-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 188097 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 188115 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 188170 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37266.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188171 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39665.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188199 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39665.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188200 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_39665.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188201 INFO  (jetty-launcher-930-thread-3) 
[n:127.0.0.1:39665_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node2/.
   [junit4]   2> 188224 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37266.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188228 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37266.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188230 INFO  (jetty-launcher-930-thread-2) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node3/.
   [junit4]   2> 188264 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 188265 WARN  (jetty-launcher-930-thread-4) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 188266 WARN  (jetty-launcher-930-thread-4) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@1aaf300c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 188269 WARN  (jetty-launcher-930-thread-4) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 188270 WARN  (jetty-launcher-930-thread-4) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@49d4a1cb[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 188271 INFO  (jetty-launcher-930-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
   [junit4]   2> 188284 INFO  (zkConnectionManagerCallback-980-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188305 INFO  (zkConnectionManagerCallback-982-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188314 WARN  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 188316 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 188328 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:35182_solr as DOWN
   [junit4]   2> 188330 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 188330 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35182_solr
   [junit4]   2> 188332 INFO  (zkCallback-957-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 188332 INFO  (zkCallback-971-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 188332 INFO  (zkCallback-955-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 188332 INFO  (zkCallback-966-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 188336 INFO  (zkCallback-981-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 188337 INFO  (zkConnectionManagerCallback-989-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188339 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 188346 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:34908/solr ready
   [junit4]   2> 188374 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 188398 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35182.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188412 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35182.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188412 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35182.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188416 INFO  (jetty-launcher-930-thread-4) 
[n:127.0.0.1:35182_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node4/.
   [junit4]   2> 188587 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 188587 WARN  (jetty-launcher-930-thread-1) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 188590 WARN  (jetty-launcher-930-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@705b6dd1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 188595 WARN  (jetty-launcher-930-thread-1) [    ] 
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport 
since Java 8 or lower versions does not support SSL + HTTP/2
   [junit4]   2> 188596 WARN  (jetty-launcher-930-thread-1) [    ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
SslContextFactory@3c8a09a8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 188597 INFO  (jetty-launcher-930-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
   [junit4]   2> 188600 INFO  (zkConnectionManagerCallback-996-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188605 INFO  (zkConnectionManagerCallback-998-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188611 WARN  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.ZkController The _default configset could 
not be uploaded. Please provide 'solr.default.confdir' parameter that points to 
a configset intended to be the default. Current 'solr.default.confdir' value: 
null
   [junit4]   2> 188612 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 188617 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.ZkController Publish 
node=127.0.0.1:45733_solr as DOWN
   [junit4]   2> 188620 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 188620 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45733_solr
   [junit4]   2> 188622 INFO  (zkCallback-955-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188622 INFO  (zkCallback-957-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188623 INFO  (zkCallback-971-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188623 INFO  (zkCallback-966-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188623 INFO  (zkCallback-981-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188626 INFO  (zkConnectionManagerCallback-1005-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188627 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (4)
   [junit4]   2> 188628 INFO  (zkCallback-997-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188628 INFO  (zkCallback-988-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 188629 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:34908/solr ready
   [junit4]   2> 188659 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 188683 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45733.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188697 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45733.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188697 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45733.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 188698 INFO  (jetty-launcher-930-thread-1) 
[n:127.0.0.1:45733_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node1/.
   [junit4]   2> 188769 INFO  (zkConnectionManagerCallback-1011-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 188770 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 188774 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
   [junit4]   2> 188802 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 188808 INFO  
(OverseerThreadFactory-548-thread-1-processing-n:127.0.0.1:37266_solr) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
collection1
   [junit4]   2> 188925 INFO  
(OverseerStateUpdate-73328593821040649-127.0.0.1:37266_solr-n_0000000000) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:45733/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 188935 INFO  
(OverseerStateUpdate-73328593821040649-127.0.0.1:37266_solr-n_0000000000) 
[n:127.0.0.1:37266_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"collection1_shard2_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:35182/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 189180 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr    
x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 189191 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr    
x:collection1_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create 
command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 190219 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
   [junit4]   2> 190220 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
   [junit4]   2> 190240 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
   [junit4]   2> 190240 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.IndexSchema [collection1_shard2_replica_n3] Schema name=test
   [junit4]   2> 190350 WARN  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 190350 WARN  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 190351 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 190351 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 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> 190352 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45733.solr.core.collection1.shard1.replica_n1' (registry 
'solr.core.collection1.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 190352 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node1/collection1_shard1_replica_n1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/]
   [junit4]   2> 190409 WARN  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for 
multiple copyFields (2)
   [junit4]   2> 190409 WARN  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple 
copyFields (3)
   [junit4]   2> 190410 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 190410 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n3' using 
configuration from collection collection1, trusted=true
   [junit4]   2> 190410 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35182.solr.core.collection1.shard2.replica_n3' (registry 
'solr.core.collection1.shard2.replica_n3') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
   [junit4]   2> 190411 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.SolrCore [[collection1_shard2_replica_n3] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node4/collection1_shard2_replica_n3],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node4/./collection1_shard2_replica_n3/data/]
   [junit4]   2> 190438 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 190438 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 190439 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 190439 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 190441 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7839d4d1[collection1_shard1_replica_n1] main]
   [junit4]   2> 190446 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 190447 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 190447 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 190448 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1633953168586964992
   [junit4]   2> 190462 INFO  
(searcherExecutor-570-thread-1-processing-n:127.0.0.1:45733_solr 
x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) 
[n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@7839d4d1[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 190476 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard1 to Terms{values={core_node2=0}, version=0}
   [junit4]   2> 190476 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/collection1/leaders/shard1
   [junit4]   2> 190480 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 190480 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 190480 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/
   [junit4]   2> 190482 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 190482 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/ has no replicas
   [junit4]   2> 190482 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 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/73328593821040657-core_node2-n_0000000000
   [junit4]   2> 190486 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/ shard1
   [junit4]   2> 190524 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 190524 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 190526 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 190526 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 190531 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@11e5f89[collection1_shard2_replica_n3] main]
   [junit4]   2> 190534 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 190534 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 190535 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 190535 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1633953168678191104
   [junit4]   2> 190541 INFO  
(searcherExecutor-571-thread-1-processing-n:127.0.0.1:35182_solr 
x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) 
[n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n3] o.a.s.c.SolrCore 
[collection1_shard2_replica_n3] Registered new searcher 
Searcher@11e5f89[collection1_shard2_replica_n3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 190547 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
   [junit4]   2> 190547 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created 
/collections/collection1/leaders/shard2
   [junit4]   2> 190552 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 190553 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 190553 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/
   [junit4]   2> 190563 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 190564 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.SyncStrategy 
https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/ has no replicas
   [junit4]   2> 190564 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node 
/collections/collection1/leaders/shard2/leader after winning as 
/collections/collection1/leader_elect/shard2/election/73328593821040654-core_node4-n_0000000000
   [junit4]   2> 190567 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/ shard2
   [junit4]   2> 190670 INFO  (zkCallback-997-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190670 INFO  (zkCallback-981-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190672 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 190672 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 190677 INFO  (zkCallback-997-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190677 INFO  (zkCallback-981-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190678 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1487
   [junit4]   2> 190680 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1500
   [junit4]   2> 190693 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
45 seconds. Check all shard replicas
   [junit4]   2> 190789 INFO  (zkCallback-997-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190789 INFO  (zkCallback-997-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190789 INFO  (zkCallback-981-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190789 INFO  (zkCallback-981-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190791 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=1989
   [junit4]   2> 190792 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: 
collection1 failOnTimeout:true timeout (sec):45
   [junit4]   2> 190800 INFO  (zkCallback-997-thread-3) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190808 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
   [junit4]   2> 190829 INFO  (zkCallback-981-thread-3) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/collection1/state.json] for collection [collection1] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 190888 INFO  
(OverseerCollectionConfigSetProcessor-73328593821040649-127.0.0.1:37266_solr-n_0000000000)
 [n:127.0.0.1:37266_solr    ] 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> 191024 INFO  
(TEST-MathExpressionTest.testDescribe-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testDescribe
   [junit4]   2> 191191 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard2 to Terms{values={core_node4=1}, version=1}
   [junit4]   2> 191192 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953169362911232)} 0 3
   [junit4]   2> 191196 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953169371299840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 191197 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 191199 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 191199 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 3
   [junit4]   2> 191202 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953169377591296,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 191202 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 191213 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 191213 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection1/terms/shard1 to Terms{values={core_node2=1}, version=1}
   [junit4]   2> 191214 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953169207721984),commit=} 0 177
   [junit4]   2> 191260 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[1 
(1633953169434214400), 4 (1633953169438408704)]} 0 4
   [junit4]   2> 191267 INFO  (qtp715068116-2648) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[2 
(1633953169434214400), 3 (1633953169444700160), 5 (1633953169444700161), 6 
(1633953169444700162), 7 (1633953169445748736)]} 0 12
   [junit4]   2> 191453 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953169640783872,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 191453 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b08c336 
commitCommandVersion:1633953169640783872
   [junit4]   2> 191465 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@53e3c362[collection1_shard1_replica_n1] main]
   [junit4]   2> 191470 INFO  
(searcherExecutor-570-thread-1-processing-n:127.0.0.1:45733_solr 
x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) 
[n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@53e3c362[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.2.0):c2:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.2.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1558259172106}]))}
   [junit4]   2> 191471 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 191471 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 18
   [junit4]   2> 191480 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953169669095424,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 191497 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1482626e 
commitCommandVersion:1633953169669095424
   [junit4]   2> 191510 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2ac8ca71[collection1_shard2_replica_n3] main]
   [junit4]   2> 191510 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 191512 INFO  
(searcherExecutor-571-thread-1-processing-n:127.0.0.1:35182_solr 
x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) 
[n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n3] o.a.s.c.SolrCore 
[collection1_shard2_replica_n3] Registered new searcher 
Searcher@2ac8ca71[collection1_shard2_replica_n3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.2.0):c5:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.2.0, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, 
timestamp=1558259172150}]))}
   [junit4]   2> 191513 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{commit=} 0 
244
   [junit4]   2> 191582 INFO  (qtp1701003413-2629) [n:127.0.0.1:39665_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 191597 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/stream 
params={indent=off&expr=let(a%3Dsearch(collection1,+q%3D"col_s:a",+fl%3D"price_f,+order_i",+sort%3D"order_i+asc"),+b%3Dcol(a,+price_f),++stats%3Ddescribe(b))&wt=json&version=2.2}
 status=0 QTime=8
   [junit4]   2> 191602 INFO  (zkConnectionManagerCallback-1018-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 191605 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 191609 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
   [junit4]   2> 191786 INFO  (qtp715068116-2648) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&rows=10&version=2&q=col_s:a&NOW=1558259172261&isShard=true&wt=javabin}
 hits=5 status=0 QTime=0
   [junit4]   2> 191788 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection1:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&rows=10&version=2&q=col_s:a&NOW=1558259172261&isShard=true&wt=javabin}
 hits=2 status=0 QTime=7
   [junit4]   2> 191854 INFO  (qtp715068116-2646) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/select 
params={q=col_s:a&df=text&distrib=false&_stateVer_=collection1:4&fl=price_f,+order_i&fl=id&shards.purpose=64&NOW=1558259172261&ids=2,3,5,6,7&isShard=true&shard.url=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 191869 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/select 
params={q=col_s:a&df=text&distrib=false&_stateVer_=collection1:4&fl=price_f,+order_i&fl=id&shards.purpose=64&NOW=1558259172261&ids=1,4&isShard=true&shard.url=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 191885 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/select 
params={q=col_s:a&_stateVer_=collection1:4&fl=price_f,+order_i&sort=order_i+asc&wt=javabin&version=2}
 hits=7 status=0 QTime=268
   [junit4]   2> 191902 INFO  
(TEST-MathExpressionTest.testDescribe-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testDescribe
   [junit4]   2> 192027 INFO  
(TEST-MathExpressionTest.testChiSquareDataSet-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testChiSquareDataSet
   [junit4]   2> 192033 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@90a778c[collection1_shard2_replica_n3] realtime]
   [junit4]   2> 192036 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2a3e883e[collection1_shard1_replica_n1] realtime]
   [junit4]   2> 192036 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953170250006528)} 0 2
   [junit4]   2> 192047 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953170263638016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 192049 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b08c336 
commitCommandVersion:1633953170263638016
   [junit4]   2> 192050 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@17ce10ba[collection1_shard1_replica_n1] main]
   [junit4]   2> 192050 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 192052 INFO  
(searcherExecutor-570-thread-1-processing-n:127.0.0.1:45733_solr 
x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) 
[n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 
x:collection1_shard1_replica_n1] o.a.s.c.SolrCore 
[collection1_shard1_replica_n1] Registered new searcher 
Searcher@17ce10ba[collection1_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 192052 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 6
   [junit4]   2> 192064 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953170281463808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 192064 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1482626e 
commitCommandVersion:1633953170281463808
   [junit4]   2> 192064 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2d9a100d[collection1_shard2_replica_n3] main]
   [junit4]   2> 192065 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 192066 INFO  
(searcherExecutor-571-thread-1-processing-n:127.0.0.1:35182_solr 
x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) 
[n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 
x:collection1_shard2_replica_n3] o.a.s.c.SolrCore 
[collection1_shard2_replica_n3] Registered new searcher 
Searcher@2d9a100d[collection1_shard2_replica_n3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 192067 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953170244763648),commit=} 0 38
   [junit4]   2> 192084 INFO  (qtp715068116-2648) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/stream 
params={indent=off&expr=let(echo%3Dtrue,++++a%3Darray(1,1,2,3,4,5,6,7,9,10,11,12),+++++b%3Darray(1,1,2,3,4,5,6,7,1,1,1,1),+++++chisquare%3DchiSquareDataSet(a,+b))&wt=json&version=2.2}
 status=0 QTime=2
   [junit4]   2> 192098 INFO  
(TEST-MathExpressionTest.testChiSquareDataSet-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testChiSquareDataSet
   [junit4]   2> 192227 INFO  
(TEST-MathExpressionTest.testNatural-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testNatural
   [junit4]   2> 192231 INFO  (qtp487406579-2641) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953170455527424)} 0 1
   [junit4]   2> 192252 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953170478596096,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 192252 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 192252 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 192252 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 192257 INFO  (qtp715068116-2646) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953170483838976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 192257 INFO  (qtp715068116-2646) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 192257 INFO  (qtp715068116-2646) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 192257 INFO  (qtp715068116-2646) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953170454478848),commit=} 0 28
   [junit4]   2> 192269 INFO  (qtp487406579-2640) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.c.S.Request [collection1_shard1_replica_n1]  webapp=/solr path=/stream 
params={indent=off&expr=natural(6)&wt=json&version=2.2} status=0 QTime=0
   [junit4]   2> 192272 INFO  
(TEST-MathExpressionTest.testNatural-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testNatural
   [junit4]   2> 192385 INFO  
(TEST-MathExpressionTest.testGammaDistribution-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testGammaDistribution
   [junit4]   2> 192391 INFO  (qtp487406579-2641) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953170622251008)} 0 2
   [junit4]   2> 192410 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953170644271104,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 192410 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 192410 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 192410 INFO  (qtp487406579-2637) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 192411 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953170645319680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 192411 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 192412 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 192412 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953170619105280),commit=} 0 26
   [junit4]   2> 192421 INFO  (qtp715068116-2650) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/stream 
params={indent=off&expr=#comment%0alet(echo%3Dtrue,+a%3Ddescribe(sample(gammaDistribution(1,+10),10000)),+%0a#+commment%0ab%3Ddescribe(sample(gammaDistribution(3,+10),10000)),+c%3Ddescribe(sample(gammaDistribution(5,+10),10000)),d%3Ddescribe(sample(gammaDistribution(7,+10),10000)),e%3Dmean(sample(gammaDistribution(1,+10),10000)),f%3Dmean(sample(gammaDistribution(1,+20),10000)),g%3Dmean(sample(gammaDistribution(1,+30),10000)))&wt=json&version=2.2}
 status=0 QTime=2
   [junit4]   2> 193039 INFO  
(TEST-MathExpressionTest.testGammaDistribution-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testGammaDistribution
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=MathExpressionTest 
-Dtests.method=testGammaDistribution -Dtests.seed=E3F8F49E99795575 
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=cs 
-Dtests.timezone=Pacific/Pitcairn -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 0.78s J0 | MathExpressionTest.testGammaDistribution <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: 0.8270447626785014 
0.8286288686448905
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E3F8F49E99795575:DE82DF30BA01FF62]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution(MathExpressionTest.java:4590)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 193124 INFO  
(TEST-MathExpressionTest.testSumSq-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSumSq
   [junit4]   2> 193128 INFO  (qtp487406579-2638) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953171396100096)} 0 1
   [junit4]   2> 193131 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953171400294400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 193131 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 193131 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 193132 INFO  (qtp487406579-2639) [n:127.0.0.1:45733_solr 
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 1
   [junit4]   2> 193133 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953171402391552,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 193133 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 193133 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 193133 INFO  (qtp715068116-2647) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953171395051520),commit=} 0 7
   [junit4]   2> 193140 INFO  (qtp715068116-2648) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.c.S.Request [collection1_shard2_replica_n3]  webapp=/solr path=/stream 
params={indent=off&expr=sumSq(array(-3,-2.5,+10))&wt=json&version=2.2} status=0 
QTime=1
   [junit4]   2> 193142 INFO  
(TEST-MathExpressionTest.testSumSq-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testSumSq
   [junit4]   2> 193228 INFO  
(TEST-MathExpressionTest.testScale-seed#[E3F8F49E99795575]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testScale
   [junit4]   2> 193232 INFO  (qtp715068116-2646) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3]  
webapp=/solr path=/update 
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
 (-1633953171505152000)} 0 1
   [junit4]   2> 193238 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1633953171512492032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 193238 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 193239 INFO  (qtp715068116-2649) [n:127.0.0.1:35182_solr 
c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]

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

    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 220772 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 220773 INFO  (jetty-closer-1026-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@201cf1f: rootName = 
solr_45733, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@56676757
   [junit4]   2> 220773 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@780e8123: rootName = 
solr_35182, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@6a742906
   [junit4]   2> 220781 INFO  (jetty-closer-1026-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 220781 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 220781 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2fb0556: rootName = 
solr_35182, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@5617ac2f
   [junit4]   2> 220781 INFO  (jetty-closer-1026-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@41c577fa: rootName = 
solr_45733, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@5617ac2f
   [junit4]   2> 220784 INFO  (jetty-closer-1026-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 220784 INFO  (jetty-closer-1026-thread-4) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4d5e08f9: rootName = 
solr_45733, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@119f175d
   [junit4]   2> 220785 INFO  (jetty-closer-1026-thread-4) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 220787 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 220787 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1f95795c: rootName = 
solr_35182, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@119f175d
   [junit4]   2> 220787 INFO  (jetty-closer-1026-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 220800 INFO  (zkCallback-988-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 220801 INFO  (zkCallback-988-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 220809 INFO  (jetty-closer-1026-thread-4) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@1c9b6f82{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 220814 INFO  (jetty-closer-1026-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3a9acf34{/solr,null,UNAVAILABLE}
   [junit4]   2> 220815 INFO  (jetty-closer-1026-thread-4) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 220820 INFO  (jetty-closer-1026-thread-3) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@43260df0{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 220828 INFO  (jetty-closer-1026-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@10fc2a14{/solr,null,UNAVAILABLE}
   [junit4]   2> 220828 INFO  (jetty-closer-1026-thread-3) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 220831 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 220838 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>        10      /solr/aliases.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2>        3       /solr/collections/collection1/terms/shard2
   [junit4]   2>        3       /solr/collections/collection1/terms/shard1
   [junit4]   2>        2       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        17      /solr/collections/collection1/state.json
   [junit4]   2>        10      /solr/clusterstate.json
   [junit4]   2>        10      /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        10      /solr/live_nodes
   [junit4]   2>        10      /solr/collections
   [junit4]   2> 
   [junit4]   2> 220843 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34908
   [junit4]   2> 220843 INFO  
(SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 34908
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001
   [junit4]   2> May 19, 2019 9:46:41 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 3 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: 
codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
 chunkSize=10, maxDocsPerChunk=180, blockSize=1), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, 
chunkSize=10, blockSize=1)), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@49e86d7e),
 locale=cs, timezone=Pacific/Pitcairn
   [junit4]   2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 
1.8.0_191 (64-bit)/cpus=4,threads=1,free=131541400,total=407371776
   [junit4]   2> NOTE: All tests run in this JVM: [RegressionEvaluatorTest, 
TestValidatingJsonMap, HeatmapFacetMapTest, CommonParamsTest, 
CloudHttp2SolrClientMultiConstructorTest, NormalizeEvaluatorTest, 
ConcurrentUpdateHttp2SolrClientBadInputTest, ExclusiveOrEvaluatorTest, 
SolrExceptionTest, SolrExampleXMLHttp2Test, SolrPingTest, 
SolrExampleEmbeddedTest, CloudSolrClientBadInputTest, 
HyperbolicSineEvaluatorTest, DocumentAnalysisResponseTest, JDBCStreamTest, 
DivideEvaluatorTest, TestObjectBuilder, TestFastWriter, TangentEvaluatorTest, 
ArcCosineEvaluatorTest, PowerEvaluatorTest, HttpClientUtilTest, 
ConcatOperationTest, TestJsonRecordReader, CosineEvaluatorTest, 
JsonRequestApiHeatmapFacetingTest, JsonQueryRequestIntegrationTest, 
LargeVolumeEmbeddedTest, LBHttpSolrClientBuilderTest, SolrParamTest, GraphTest, 
JsonRequestApiTest, UsingSolrJRefGuideExamplesTest, ZkConfigFilesTest, 
CollectionAdminRequestRequiredParamsTest, GetByIdTest, 
SolrExampleBinaryHttp2Test, SolrQueryTest, SolrSchemalessExampleTest, 
TestBatchUpdate, TestSolrJErrorHandling, SolrExampleStreamingHttp2Test, 
BasicHttpSolrClientTest, CloudSolrClientCacheTest, 
ConcurrentUpdateHttp2SolrClientTest, HttpSolrClientBadInputTest, 
HttpSolrClientBuilderTest, HttpSolrClientConPoolTest, 
HttpSolrClientSSLAuthConPoolTest, TestCloudSolrClientConnections, 
MathExpressionTest]
   [junit4] Completed [124/196 (1!)] on J0 in 33.61s, 119 tests, 1 failure <<< 
FAILURES!

[...truncated 49053 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: 
invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid 
part, must have at least one section (e.g., chapter, appendix, etc.)
     [java] Processed 2531 links (2070 relative) to 3359 anchors in 253 files
     [echo] Validated Links & Anchors via: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-ref-guide/bare-bones-html/

-documentation-lint:
    [jtidy] Checking for broken html (such as invalid tags)...
   [delete] Deleting directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/build/jtidy_tmp
     [echo] Checking for broken links...
     [exec] 
     [exec] Crawl/parse...
     [exec] 
     [exec] Verify...
     [echo] Checking for malformed docs...
     [exec] 
     [exec] 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/docs/solr-solrj/overview-summary.html
     [exec]   missing description: org.noggit
     [exec] 
     [exec] Missing javadocs were found!

BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.xml:643:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.xml:101:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:660:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:676:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/common-build.xml:2530:
 exec returned: 1

Total time: 111 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to