You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/05/03 03:49:05 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-11.0.2) - Build #
24025 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24025/
Java: 64bit/jdk-11.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution
Error Message:
0.8348708547832137 0.8414730550069032
Stack Trace:
java.lang.AssertionError: 0.8348708547832137 0.8414730550069032
at __randomizedtesting.SeedInfo.seed([EA6F077F54B556F5:D7152CD177CDFCE2]: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 java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
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 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.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15999 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[junit4] 2> 47092 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/init-core-data-001
[junit4] 2> 47092 WARN (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 47092 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 47093 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 47093 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001
[junit4] 2> 47093 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 47093 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 47093 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 47193 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:36173
[junit4] 2> 47193 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36173
[junit4] 2> 47193 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36173
[junit4] 2> 47196 INFO (zkConnectionManagerCallback-298-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47198 INFO (zkConnectionManagerCallback-300-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47199 INFO (zkConnectionManagerCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47201 WARN (jetty-launcher-303-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 47201 WARN (jetty-launcher-303-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 47201 WARN (jetty-launcher-303-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 47201 WARN (jetty-launcher-303-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
[junit4] 2> 47201 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
[junit4] 2> 47213 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 47213 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 47213 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15c76a21{/solr,null,AVAILABLE}
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e6d10ad{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:34635}
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.e.j.s.Server Started @47240ms
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34635}
[junit4] 2> 47216 ERROR (jetty-launcher-303-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-03T03:37:31.713722Z
[junit4] 2> 47218 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5863e138{/solr,null,AVAILABLE}
[junit4] 2> 47219 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 47219 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 47219 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 47219 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@24b20d04{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46699}
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.e.j.s.Server Started @47246ms
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46699}
[junit4] 2> 47222 ERROR (jetty-launcher-303-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-03T03:37:31.719556Z
[junit4] 2> 47222 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cfad204{/solr,null,AVAILABLE}
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1f744238{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37211}
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.e.j.s.Server Started @47247ms
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37211}
[junit4] 2> 47223 ERROR (jetty-launcher-303-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-03T03:37:31.720585Z
[junit4] 2> 47226 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 47226 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 47226 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 47231 INFO (zkConnectionManagerCallback-305-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47231 INFO (zkConnectionManagerCallback-307-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47231 INFO (zkConnectionManagerCallback-309-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47231 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 47231 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d5af27c{/solr,null,AVAILABLE}
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c49acf9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37171}
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ] o.e.j.s.Server Started @47256ms
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37171}
[junit4] 2> 47232 ERROR (jetty-launcher-303-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 47233 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 47233 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 47233 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-03T03:37:31.730058Z
[junit4] 2> 47239 INFO (zkConnectionManagerCallback-311-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47239 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 47462 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 47469 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 47482 WARN (jetty-launcher-303-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@781321d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47484 WARN (jetty-launcher-303-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6f4545ea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47490 WARN (jetty-launcher-303-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@e4e34d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47490 INFO (jetty-launcher-303-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
[junit4] 2> 47491 WARN (jetty-launcher-303-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@76c58e91[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47491 INFO (jetty-launcher-303-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
[junit4] 2> 47492 INFO (zkConnectionManagerCallback-324-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47492 INFO (zkConnectionManagerCallback-327-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47493 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100743a702c0007, likely client has closed socket
[junit4] 2> 47530 INFO (zkConnectionManagerCallback-330-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47535 INFO (zkConnectionManagerCallback-331-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47536 WARN (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_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> 47537 WARN (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_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> 47538 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37211_solr
[junit4] 2> 47538 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.Overseer Overseer (id=72185388376784905-127.0.0.1:37211_solr-n_0000000000) starting
[junit4] 2> 47541 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37171_solr
[junit4] 2> 47555 INFO (zkCallback-329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 47558 INFO (zkCallback-328-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 47566 INFO (zkConnectionManagerCallback-340-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47567 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 47568 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36173/solr ready
[junit4] 2> 47574 INFO (OverseerStateUpdate-72185388376784905-127.0.0.1:37211_solr-n_0000000000) [n:127.0.0.1:37211_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37211_solr
[junit4] 2> 47585 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 47595 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37211_solr as DOWN
[junit4] 2> 47595 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 47595 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37211_solr
[junit4] 2> 47596 INFO (zkCallback-329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 47598 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 47600 WARN (jetty-launcher-303-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6064413f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47602 INFO (zkCallback-339-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 47627 WARN (jetty-launcher-303-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@71f29d56[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47627 INFO (jetty-launcher-303-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
[junit4] 2> 47631 INFO (zkCallback-328-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 47631 WARN (jetty-launcher-303-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1c9a4211[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47635 INFO (zkConnectionManagerCallback-345-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47636 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 47637 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36173/solr ready
[junit4] 2> 47638 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 47642 INFO (zkConnectionManagerCallback-353-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47650 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 47651 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37211.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47659 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37211.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47659 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37211.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47659 INFO (zkConnectionManagerCallback-357-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47661 INFO (jetty-launcher-303-thread-4) [n:127.0.0.1:37211_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node4/.
[junit4] 2> 47662 WARN (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_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> 47663 WARN (jetty-launcher-303-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@627892fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47664 INFO (jetty-launcher-303-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
[junit4] 2> 47665 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 47667 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34635_solr as DOWN
[junit4] 2> 47668 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 47668 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34635_solr
[junit4] 2> 47670 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37171.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47673 INFO (zkCallback-328-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 47673 INFO (zkCallback-329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 47674 INFO (zkCallback-339-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 47681 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37171.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47681 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37171.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47682 INFO (zkCallback-344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 47682 INFO (zkCallback-356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 47682 INFO (jetty-launcher-303-thread-1) [n:127.0.0.1:37171_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node1/.
[junit4] 2> 47695 INFO (zkConnectionManagerCallback-363-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47710 INFO (zkConnectionManagerCallback-368-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47711 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 47712 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36173/solr ready
[junit4] 2> 47720 INFO (zkConnectionManagerCallback-370-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47721 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 47723 WARN (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_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> 47723 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 47726 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46699_solr as DOWN
[junit4] 2> 47728 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 47728 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46699_solr
[junit4] 2> 47729 INFO (zkCallback-328-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47729 INFO (zkCallback-344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47729 INFO (zkCallback-339-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47729 INFO (zkCallback-356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47729 INFO (zkCallback-329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47735 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34635.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47738 INFO (zkCallback-367-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47742 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34635.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47742 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34635.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47743 INFO (zkCallback-369-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 47743 INFO (jetty-launcher-303-thread-2) [n:127.0.0.1:34635_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node2/.
[junit4] 2> 47750 INFO (zkConnectionManagerCallback-378-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47751 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 47752 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36173/solr ready
[junit4] 2> 47758 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 47770 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46699.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47777 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46699.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47777 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46699.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 47778 INFO (jetty-launcher-303-thread-3) [n:127.0.0.1:46699_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node3/.
[junit4] 2> 47843 INFO (zkConnectionManagerCallback-384-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47845 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 47846 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36173/solr ready
[junit4] 2> 47856 INFO (qtp1792819914-925) [n:127.0.0.1:34635_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> 47858 INFO (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:37211_solr) [n:127.0.0.1:37211_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 47967 INFO (OverseerStateUpdate-72185388376784905-127.0.0.1:37211_solr-n_0000000000) [n:127.0.0.1:37211_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":"http://127.0.0.1:37211/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 47970 INFO (OverseerStateUpdate-72185388376784905-127.0.0.1:37211_solr-n_0000000000) [n:127.0.0.1:37211_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":"http://127.0.0.1:37171/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 48179 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 48187 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 48188 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr x:collection1_shard2_replica_n3] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 49202 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 49210 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 49212 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49215 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 49217 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 49218 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 49219 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 49223 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49224 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 49272 WARN (qtp1652825880-918) [n:127.0.0.1:37211_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> 49272 WARN (qtp1652825880-918) [n:127.0.0.1:37211_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> 49272 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49272 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49273 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37211.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 49273 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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 [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node4/collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node4/./collection1_shard1_replica_n1/data/]
[junit4] 2> 49307 WARN (qtp546855652-933) [n:127.0.0.1:37171_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> 49307 WARN (qtp546855652-933) [n:127.0.0.1:37171_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> 49307 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49308 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49308 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37171.solr.core.collection1.shard2.replica_n3' (registry 'solr.core.collection1.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
[junit4] 2> 49308 INFO (qtp546855652-933) [n:127.0.0.1:37171_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 [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node1/collection1_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node1/./collection1_shard2_replica_n3/data/]
[junit4] 2> 49329 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49329 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49331 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 49331 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 49333 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2fdc71e4[collection1_shard1_replica_n1] main]
[junit4] 2> 49334 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49334 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49334 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49335 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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 1632480425496543232
[junit4] 2> 49337 INFO (searcherExecutor-188-thread-1-processing-n:127.0.0.1:37211_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:37211_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@2fdc71e4[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 49339 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49339 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/
[junit4] 2> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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/72185388376784905-core_node2-n_0000000000
[junit4] 2> 49342 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 49353 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49353 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49355 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 49355 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 49356 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@161ef722[collection1_shard2_replica_n3] main]
[junit4] 2> 49357 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49357 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49358 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49358 INFO (qtp546855652-933) [n:127.0.0.1:37171_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 1632480425520660480
[junit4] 2> 49361 INFO (searcherExecutor-189-thread-1-processing-n:127.0.0.1:37171_solr x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:37171_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@161ef722[collection1_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 49363 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49363 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/
[junit4] 2> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49365 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SyncStrategy http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/ has no replicas
[junit4] 2> 49365 INFO (qtp546855652-933) [n:127.0.0.1:37171_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/72185388376784906-core_node4-n_0000000000
[junit4] 2> 49366 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/ shard2
[junit4] 2> 49467 INFO (zkCallback-328-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> 49467 INFO (zkCallback-328-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> 49467 INFO (zkCallback-329-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> 49467 INFO (zkCallback-329-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> 49468 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49468 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49493 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=1314
[junit4] 2> 49493 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=1306
[junit4] 2> 49498 INFO (qtp1792819914-925) [n:127.0.0.1:34635_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> 49570 INFO (zkCallback-356-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> 49570 INFO (zkCallback-328-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> 49570 INFO (zkCallback-328-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> 49570 INFO (zkCallback-329-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> 49570 INFO (zkCallback-329-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> 49570 INFO (zkCallback-328-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> 49570 INFO (zkCallback-329-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> 49570 INFO (qtp1792819914-925) [n:127.0.0.1:34635_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=1714
[junit4] 2> 49578 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):45
[junit4] 2> 49579 INFO (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 49597 INFO (TEST-MathExpressionTest.testAddAll-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddAll
[junit4] 2> 49616 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49616 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480425790144512)} 0 1
[junit4] 2> 49618 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425793290240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49619 INFO (qtp546855652-934) [n:127.0.0.1:37171_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> 49619 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49619 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425795387392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=*:* (-1632480425776513024),commit=} 0 21
[junit4] 2> 49626 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=addAll(array(1,+2,+3),+array(4.5,+5.5,+6.5),+array(7,8,9))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 49629 INFO (TEST-MathExpressionTest.testAddAll-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAddAll
[junit4] 2> 49647 INFO (TEST-MathExpressionTest.testMemsetSize-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMemsetSize
[junit4] 2> 49652 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480425827893248)} 0 0
[junit4] 2> 49654 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425831038976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49654 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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> 49654 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49655 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425832087552,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=*:* (-1632480425824747520),commit=} 0 7
[junit4] 2> 49660 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=let(echo%3D"b,+c",++++++++++++++a%3Dmemset(plist(tuple(field1%3Dval(1),+field2%3Dval(10)),+tuple(field1%3Dval(2),+field2%3Dval(20))),++++++++++++++++++++++++cols%3D"field1,+field2",++++++++++++++++++++++++vars%3D"f1,+f2",+++++++++++++++++++++++size%3D1),++++++++++++++b%3Dadd(f1),++++++++++++++c%3Dadd(f2))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 49662 INFO (TEST-MathExpressionTest.testMemsetSize-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMemsetSize
[junit4] 2> 49686 INFO (TEST-MathExpressionTest.testOnes-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOnes
[junit4] 2> 49709 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480425878224896)} 0 10
[junit4] 2> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425890807808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_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> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_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=http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425891856384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=*:* (-1632480425876127744),commit=} 0 15
[junit4] 2> 49733 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=ones(6)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 49734 INFO (TEST-MathExpressionTest.testOnes-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOnes
[junit4] 2> 49783 INFO (TEST-MathExpressionTest.testSumDifference-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSumDifference
[junit4] 2> 49787 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480425969451008)} 0 1
[junit4] 2> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425973645312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 49791 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480425974693888,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49791 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49792 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49792 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=*:* (-1632480425968402432),commit=} 0 7
[junit4] 2> 49808 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=sumDifference(array(2,4,6,8,10,12),array(1,2,3,4,5,6))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 49834 INFO (TEST-MathExpressionTest.testSumDifference-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSumDifference
[junit4] 2> 49863 INFO (OverseerCollectionConfigSetProcessor-72185388376784905-127.0.0.1:37211_solr-n_0000000000) [n:127.0.0.1:37211_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> 49892 INFO (TEST-MathExpressionTest.testExponentialMovingAverage-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExponentialMovingAverage
[junit4] 2> 49901 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480426088988672)} 0 0
[junit4] 2> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426092134400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 49903 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426092134400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49903 INFO (qtp546855652-934) [n:127.0.0.1:37171_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> 49904 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49904 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=*:* (-1632480426087940096),commit=} 0 4
[junit4] 2> 49930 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=expMovingAvg(array(22.27,+22.19,+22.08,+22.17,+22.18,+22.13,+22.23,+22.43,+22.24,+22.29,+22.15,+22.39,+22.38,+22.61,+23.36,+24.05,+23.75,+23.83,+23.95,+23.63,+23.82,+23.87,+23.65,+23.19,23.10,+23.33,+22.68,+23.10,+22.40,+22.17),+10)&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 49943 INFO (TEST-MathExpressionTest.testExponentialMovingAverage-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExponentialMovingAverage
[junit4] 2> 49996 INFO (TEST-MathExpressionTest.testConvexHull-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConvexHull
[junit4] 2> 50002 INFO (qtp546855652-932) [n:127.0.0.1:37171_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=http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480426193846272)} 0 2
[junit4] 2> 50004 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426198040576,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 50004 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 50005 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 50005 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426201186304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=*:* (-1632480426191749120),commit=} 0 9
[junit4] 2> 50039 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(echo%3Dtrue,++++++++++++++x%3Darray(96.42894739701268,+99.11076410926444,+95.71563821370013,101.4356840561301,+96.17912865782684,+113.430677406492,+109.5927785287056,+87.26561260238425,+103.3122002816537,+100.4959815617706,+92.78972440872515,+92.98815024042877,+89.1448359089767,+104.9410622701036,+106.5546761317927,+102.0132643274808,+119.6726096270366,+97.61388415294184,+106.7928221374049,+94.31369945729962,+87.37098859879977,+82.8015657665458,+88.84342877874248,+94.58797342988339,+92.38720473619748),++++++++++++++y%3Darray(97.43395922838836,+109.5441846957560,+78.82698890096127,+96.67181538737611,95.52423701473863,+85.3391529394878,+87.01956497912255,+111.5289690656729,86.41034184809114,+84.11696923489203,+109.3874354244069,+102.3391063812790,109.0604436531823,102.7957014900897,114.4376483055848,107.4387578165579,106.2490201384653,103.4490197583837,93.8201540211101,101.6060721649409,+115.3512636715722,119.1046170610335,99.74910277836263,104.2116724112481,+86.02222520549304),++++++++++++++c%3Dtranspose(matrix(x,+y)),++++++++++++++d%3DconvexHull(c),++++++++++++++e%3DgetVertices(d),++++++++++++++f%3DgetArea(d),++++++++++++++g%3DgetBoundarySize(d),++++++++++++++h%3DgetBaryCenter(d))&wt=json&version=2.2} status=0 QTime=3
[junit4] 2> 50103 INFO (TEST-MathExpressionTest.testConvexHull-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConvexHull
[junit4] 2> 50143 INFO (TEST-MathExpressionTest.testGaussfit-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGaussfit
[junit4] 2> 50146 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480426346938368)} 0 0
[junit4] 2> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426349035520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 50148 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426349035520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 50148 INFO (qtp546855652-934) [n:127.0.0.1:37171_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> 50149 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 50149 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=*:* (-1632480426344841216),commit=} 0 5
[junit4] 2> 50152 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(echo%3Dtrue,+x%3Darray(79.56,81.32,82.82,84.64,86.18,87.89,89.53,91.14,92.8,94.43,96.08,97.72,99.37,101,102.66,104.3,105.94,107.59,109.23,110.87,112.52,114.13,115.82,117.44,119.27),+y%3Darray(3,+3,+26,+54,+139,+344,+685,+1289,+2337,+3593,+4781,+5964,+6538,+6357,+5705,+4548,+3280,+2058,+1191,+649,+285,+112,+34,+18,+7),g%3Dgaussfit(x,y))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 50173 INFO (TEST-MathExpressionTest.testGaussfit-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGaussfit
[junit4] 2> 50194 INFO (TEST-MathExpressionTest.testZplot-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZplot
[junit4] 2> 50196 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1632480426399367168)} 0 0
[junit4] 2> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426401464320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1632480426401464320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_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> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_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=*:* (-1632480426398318592),commit=} 0 3
[junit4] 2> 50202 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(c%3Dtuple(a%3Dadd(1,2),+b%3Dadd(2,3)),+++++++++++++++zplot(table%3Dc))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 50205 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=let(c%3Dlist(tuple(a%3Dadd(1,2),+b%3Dadd(2,3)),+tuple(a%3Dadd(1,3),+b%3Dadd(2,4))),++++++++zplot(table%3Dc))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 50207 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(a%3Darray(1,2,3,4),++++++++b%3Darray(10,11,12,13),++++++++zplot(x%3Da,+y%3Db))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 50210 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=zplot(dist%3DbinomialDistribution(10,+.50))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 50327 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=zplot(dist%3DnormalDistribution(100,+10))&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 50455 INFO (TEST-MathExpressionTest.testZplot-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZplot
[junit4] 2> 50475 INFO (TEST-MathExpressionTest.testFreqTable-seed#[EA6F077F54B556F5]) [ ] o.a.s.SolrTestCase
[...truncated too long message...]
^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'queryRequest' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 4. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 19)
[ecj-lint] import javax.naming.Context;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The type javax.naming.Context is not accessible
[ecj-lint] ----------
[ecj-lint] 5. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 20)
[ecj-lint] import javax.naming.InitialContext;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The type javax.naming.InitialContext is not accessible
[ecj-lint] ----------
[ecj-lint] 6. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 21)
[ecj-lint] import javax.naming.NamingException;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The type javax.naming.NamingException is not accessible
[ecj-lint] ----------
[ecj-lint] 7. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 22)
[ecj-lint] import javax.naming.NoInitialContextException;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The type javax.naming.NoInitialContextException is not accessible
[ecj-lint] ----------
[ecj-lint] 8. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 776)
[ecj-lint] Context c = new InitialContext();
[ecj-lint] ^^^^^^^
[ecj-lint] Context cannot be resolved to a type
[ecj-lint] ----------
[ecj-lint] 9. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 776)
[ecj-lint] Context c = new InitialContext();
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] InitialContext cannot be resolved to a type
[ecj-lint] ----------
[ecj-lint] 10. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 779)
[ecj-lint] } catch (NoInitialContextException e) {
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] NoInitialContextException cannot be resolved to a type
[ecj-lint] ----------
[ecj-lint] 11. ERROR in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java (at line 781)
[ecj-lint] } catch (NamingException e) {
[ecj-lint] ^^^^^^^^^^^^^^^
[ecj-lint] NamingException cannot be resolved to a type
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 12. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/handler/admin/SegmentsInfoRequestHandler.java (at line 215)
[ecj-lint] leafReader = ((FilterLeafReader)leafReader).getDelegate();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'leafReader' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 13. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/handler/component/HttpShardHandler.java (at line 142)
[ecj-lint] return new JavaBinCodec(null, stringCache).setReadStringAsCharSeq(true);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 14. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/handler/loader/JavabinLoader.java (at line 137)
[ecj-lint] new JavaBinCodec() {
[ecj-lint] SolrParams params;
[ecj-lint] AddUpdateCommand addCmd = null;
[ecj-lint]
[ecj-lint] @Override
[ecj-lint] public List<Object> readIterator(DataInputInputStream fis) throws IOException {
[ecj-lint] while (true) {
[ecj-lint] Object o = readVal(fis);
[ecj-lint] if (o == END_OBJ) break;
[ecj-lint] if (o instanceof NamedList) {
[ecj-lint] params = ((NamedList) o).toSolrParams();
[ecj-lint] } else {
[ecj-lint] try {
[ecj-lint] if (o instanceof byte[]) {
[ecj-lint] if (params != null) req.setParams(params);
[ecj-lint] byte[] buf = (byte[]) o;
[ecj-lint] contentStreamLoader.load(req, rsp, new ContentStreamBase.ByteArrayStream(buf, null), processor);
[ecj-lint] } else {
[ecj-lint] throw new RuntimeException("unsupported type ");
[ecj-lint] }
[ecj-lint] } catch (Exception e) {
[ecj-lint] throw new RuntimeException(e);
[ecj-lint] } finally {
[ecj-lint] params = null;
[ecj-lint] req.setParams(old);
[ecj-lint] }
[ecj-lint] }
[ecj-lint] }
[ecj-lint] return Collections.emptyList();
[ecj-lint] }
[ecj-lint]
[ecj-lint] }.unmarshal(in);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 15. INFO in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/logging/log4j2/Log4j2Watcher.java (at line 187)
[ecj-lint] if (logger == root || root.equals(logger) || isRootLogger(name) || "".equals(name)) {
[ecj-lint] ^^^^^^
[ecj-lint] Unlikely argument type for equals(): Map.Entry<String,LoggerConfig> seems to be unrelated to Logger
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 16. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/update/SolrIndexSplitter.java (at line 258)
[ecj-lint] HardlinkCopyDirectoryWrapper hardLinkedDir = new HardlinkCopyDirectoryWrapper(splitDir);
[ecj-lint] ^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'hardLinkedDir' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 17. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 50)
[ecj-lint] in = new FileInputStream(src).getChannel();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] 18. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/util/FileUtils.java (at line 51)
[ecj-lint] out = new FileOutputStream(destination).getChannel();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 19. WARNING in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/util/TestInjection.java (at line 259)
[ecj-lint] timers.remove(this);
[ecj-lint] ^^^^
[ecj-lint] Unlikely argument type new TimerTask(){} for remove(Object) on a Collection<Timer>
[ecj-lint] ----------
[ecj-lint] 19 problems (8 errors, 10 warnings, 1 info)
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:634: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:101: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:681: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:2010: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:2049: Compile failed; see the compiler error output for details.
Total time: 77 minutes 56 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Re: [JENKINS] Lucene-Solr-master-Linux (64bit/jdk-11.0.2) - Build #
24025 - Failure!
Posted by Joel Bernstein <jo...@gmail.com>.
I'll create a ticket to harden this test.
Joel Bernstein
http://joelsolr.blogspot.com/
On Thu, May 2, 2019 at 11:52 PM Policeman Jenkins Server <
jenkins@thetaphi.de> wrote:
> Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/24025/
> Java: 64bit/jdk-11.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
>
> 1 tests failed.
> FAILED:
> org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution
>
> Error Message:
> 0.8348708547832137 0.8414730550069032
>
> Stack Trace:
> java.lang.AssertionError: 0.8348708547832137 0.8414730550069032
> at
> __randomizedtesting.SeedInfo.seed([EA6F077F54B556F5:D7152CD177CDFCE2]: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
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native
> Method)
> at
> java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.base/java.lang.reflect.Method.invoke(Method.java:566)
> 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
> 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.base/java.lang.Thread.run(Thread.java:834)
>
>
>
>
> Build Log:
> [...truncated 15999 lines...]
> [junit4] Suite:
> org.apache.solr.client.solrj.io.stream.MathExpressionTest
> [junit4] 2> 47092 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
> test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
> [junit4] 2> Creating dataDir:
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/init-core-data-001
> [junit4] 2> 47092 WARN
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
> [junit4] 2> 47092 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
> w/NUMERIC_DOCVALUES_SYSPROP=false
> [junit4] 2> 47093 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
> @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
> clientAuth=0.0/0.0)
> [junit4] 2> 47093 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001
> [junit4] 2> 47093 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
> [junit4] 2> 47093 INFO (ZkTestServer Run Thread) [ ]
> o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
> [junit4] 2> 47093 INFO (ZkTestServer Run Thread) [ ]
> o.a.s.c.ZkTestServer Starting server
> [junit4] 2> 47193 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.ZkTestServer start zk server on port:36173
> [junit4] 2> 47193 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36173
> [junit4] 2> 47193 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.ZkTestServer connecting to 127.0.0.1 36173
> [junit4] 2> 47196 INFO (zkConnectionManagerCallback-298-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47198 INFO (zkConnectionManagerCallback-300-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47199 INFO (zkConnectionManagerCallback-302-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47201 WARN (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
> [junit4] 2> 47201 WARN (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
> [junit4] 2> 47201 WARN (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
> [junit4] 2> 47201 WARN (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z;
> git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z;
> git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z;
> git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
> [junit4] 2> 47201 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z;
> git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.2+9
> [junit4] 2> 47213 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.session DefaultSessionIdManager workerName=node0
> [junit4] 2> 47213 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.session No SessionScavenger set, using defaults
> [junit4] 2> 47213 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.session node0 Scavenging every 660000ms
> [junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.session DefaultSessionIdManager workerName=node0
> [junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.session No SessionScavenger set, using defaults
> [junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.session node0 Scavenging every 600000ms
> [junit4] 2> 47214 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15c76a21
> {/solr,null,AVAILABLE}
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.AbstractConnector Started ServerConnector@3e6d10ad{HTTP/1.1,[http/1.1,
> h2c]}{127.0.0.1:34635}
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.e.j.s.Server Started @47240ms
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
> hostPort=34635}
> [junit4] 2> 47216 ERROR (jetty-launcher-303-thread-2) [ ]
> o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
> be missing or incomplete.
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.s.SolrDispatchFilter Using logger factory
> org.apache.logging.slf4j.Log4jLoggerFactory
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
> version 9.0.0
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
> null
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
> [junit4] 2> 47216 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
> 2019-05-03T03:37:31.713722Z
> [junit4] 2> 47218 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5863e138
> {/solr,null,AVAILABLE}
> [junit4] 2> 47219 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.session DefaultSessionIdManager workerName=node0
> [junit4] 2> 47219 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.session No SessionScavenger set, using defaults
> [junit4] 2> 47219 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.session node0 Scavenging every 660000ms
> [junit4] 2> 47219 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.AbstractConnector Started ServerConnector@24b20d04{HTTP/1.1,[http/1.1,
> h2c]}{127.0.0.1:46699}
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.e.j.s.Server Started @47246ms
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
> hostPort=46699}
> [junit4] 2> 47222 ERROR (jetty-launcher-303-thread-3) [ ]
> o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
> be missing or incomplete.
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.s.SolrDispatchFilter Using logger factory
> org.apache.logging.slf4j.Log4jLoggerFactory
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
> version 9.0.0
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
> null
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
> 2019-05-03T03:37:31.719556Z
> [junit4] 2> 47222 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cfad204
> {/solr,null,AVAILABLE}
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.AbstractConnector Started ServerConnector@1f744238{HTTP/1.1,[http/1.1,
> h2c]}{127.0.0.1:37211}
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.e.j.s.Server Started @47247ms
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
> hostPort=37211}
> [junit4] 2> 47223 ERROR (jetty-launcher-303-thread-4) [ ]
> o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
> be missing or incomplete.
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.s.SolrDispatchFilter Using logger factory
> org.apache.logging.slf4j.Log4jLoggerFactory
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
> version 9.0.0
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
> null
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
> [junit4] 2> 47223 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
> 2019-05-03T03:37:31.720585Z
> [junit4] 2> 47226 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.session DefaultSessionIdManager workerName=node0
> [junit4] 2> 47226 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.session No SessionScavenger set, using defaults
> [junit4] 2> 47226 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.session node0 Scavenging every 600000ms
> [junit4] 2> 47231 INFO (zkConnectionManagerCallback-305-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47231 INFO (zkConnectionManagerCallback-307-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47231 INFO (zkConnectionManagerCallback-309-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47231 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 47231 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5d5af27c
> {/solr,null,AVAILABLE}
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.AbstractConnector Started ServerConnector@c49acf9{HTTP/1.1,[http/1.1,
> h2c]}{127.0.0.1:37171}
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ]
> o.e.j.s.Server Started @47256ms
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
> hostPort=37171}
> [junit4] 2> 47232 ERROR (jetty-launcher-303-thread-1) [ ]
> o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may
> be missing or incomplete.
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.s.SolrDispatchFilter Using logger factory
> org.apache.logging.slf4j.Log4jLoggerFactory
> [junit4] 2> 47232 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
> version 9.0.0
> [junit4] 2> 47233 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port
> null
> [junit4] 2> 47233 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
> [junit4] 2> 47233 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
> 2019-05-03T03:37:31.730058Z
> [junit4] 2> 47239 INFO (zkConnectionManagerCallback-311-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47239 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
> [junit4] 2> 47462 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
> WhitelistHostChecker [whitelistHosts=null,
> whitelistHostCheckingEnabled=true]
> [junit4] 2> 47469 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
> WhitelistHostChecker [whitelistHosts=null,
> whitelistHostCheckingEnabled=true]
> [junit4] 2> 47482 WARN (jetty-launcher-303-thread-4) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@781321d5[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47484 WARN (jetty-launcher-303-thread-1) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@6f4545ea[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47490 WARN (jetty-launcher-303-thread-4) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@e4e34d9[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47490 INFO (jetty-launcher-303-thread-4) [ ]
> o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
> [junit4] 2> 47491 WARN (jetty-launcher-303-thread-1) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@76c58e91[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47491 INFO (jetty-launcher-303-thread-1) [ ]
> o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
> [junit4] 2> 47492 INFO (zkConnectionManagerCallback-324-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47492 INFO (zkConnectionManagerCallback-327-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47493 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [
> ] o.a.z.s.NIOServerCnxn Unable to read additional data from client
> sessionid 0x100743a702c0007, likely client has closed socket
> [junit4] 2> 47530 INFO (zkConnectionManagerCallback-330-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47535 INFO (zkConnectionManagerCallback-331-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47536 WARN (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_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> 47537 WARN (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_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> 47538 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.OverseerElectionContext I am going
> to be the leader 127.0.0.1:37211_solr
> [junit4] 2> 47538 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.Overseer Overseer
> (id=72185388376784905-127.0.0.1:37211_solr-n_0000000000) starting
> [junit4] 2> 47541 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.c.ZkController Register node as live
> in ZooKeeper:/live_nodes/127.0.0.1:37171_solr
> [junit4] 2> 47555 INFO (zkCallback-329-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
> [junit4] 2> 47558 INFO (zkCallback-328-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
> [junit4] 2> 47566 INFO (zkConnectionManagerCallback-340-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47567 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes
> from ZooKeeper... (0) -> (1)
> [junit4] 2> 47568 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider
> Cluster at 127.0.0.1:36173/solr ready
> [junit4] 2> 47574 INFO
> (OverseerStateUpdate-72185388376784905-127.0.0.1:37211_solr-n_0000000000)
> [n:127.0.0.1:37211_solr ] o.a.s.c.Overseer Starting to work on the
> main queue : 127.0.0.1:37211_solr
> [junit4] 2> 47585 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
> WhitelistHostChecker [whitelistHosts=null,
> whitelistHostCheckingEnabled=true]
> [junit4] 2> 47595 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.ZkController Publish
> node=127.0.0.1:37211_solr as DOWN
> [junit4] 2> 47595 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.TransientSolrCoreCacheDefault
> Allocating transient cache for 2147483647 transient cores
> [junit4] 2> 47595 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.ZkController Register node as live
> in ZooKeeper:/live_nodes/127.0.0.1:37211_solr
> [junit4] 2> 47596 INFO (zkCallback-329-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
> [junit4] 2> 47598 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
> WhitelistHostChecker [whitelistHosts=null,
> whitelistHostCheckingEnabled=true]
> [junit4] 2> 47600 WARN (jetty-launcher-303-thread-2) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@6064413f[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47602 INFO (zkCallback-339-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
> [junit4] 2> 47627 WARN (jetty-launcher-303-thread-2) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@71f29d56[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47627 INFO (jetty-launcher-303-thread-2) [ ]
> o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
> [junit4] 2> 47631 INFO (zkCallback-328-thread-2) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
> [junit4] 2> 47631 WARN (jetty-launcher-303-thread-3) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@1c9a4211[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47635 INFO (zkConnectionManagerCallback-345-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47636 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.c.c.ZkStateReader Updated live nodes
> from ZooKeeper... (0) -> (2)
> [junit4] 2> 47637 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider
> Cluster at 127.0.0.1:36173/solr ready
> [junit4] 2> 47638 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
> collection, keeping metrics history in memory.
> [junit4] 2> 47642 INFO (zkConnectionManagerCallback-353-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47650 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
> collection, keeping metrics history in memory.
> [junit4] 2> 47651 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37211.solr.node' (registry 'solr.node') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47659 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37211.solr.jvm' (registry 'solr.jvm') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47659 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37211.solr.jetty' (registry 'solr.jetty') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47659 INFO (zkConnectionManagerCallback-357-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47661 INFO (jetty-launcher-303-thread-4)
> [n:127.0.0.1:37211_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
> definitions underneath
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node4/.
> [junit4] 2> 47662 WARN (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_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> 47663 WARN (jetty-launcher-303-thread-3) [ ]
> o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
> SslContextFactory@627892fa[provider=null,keyStore=null,trustStore=null]
> [junit4] 2> 47664 INFO (jetty-launcher-303-thread-3) [ ]
> o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36173/solr
> [junit4] 2> 47665 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.c.ZkStateReader Updated live nodes
> from ZooKeeper... (0) -> (2)
> [junit4] 2> 47667 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.ZkController Publish
> node=127.0.0.1:34635_solr as DOWN
> [junit4] 2> 47668 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.TransientSolrCoreCacheDefault
> Allocating transient cache for 2147483647 transient cores
> [junit4] 2> 47668 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.ZkController Register node as live
> in ZooKeeper:/live_nodes/127.0.0.1:34635_solr
> [junit4] 2> 47670 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37171.solr.node' (registry 'solr.node') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47673 INFO (zkCallback-328-thread-2) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
> [junit4] 2> 47673 INFO (zkCallback-329-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
> [junit4] 2> 47674 INFO (zkCallback-339-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
> [junit4] 2> 47681 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37171.solr.jvm' (registry 'solr.jvm') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47681 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37171.solr.jetty' (registry 'solr.jetty') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47682 INFO (zkCallback-344-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
> [junit4] 2> 47682 INFO (zkCallback-356-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
> [junit4] 2> 47682 INFO (jetty-launcher-303-thread-1)
> [n:127.0.0.1:37171_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
> definitions underneath
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node1/.
> [junit4] 2> 47695 INFO (zkConnectionManagerCallback-363-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47710 INFO (zkConnectionManagerCallback-368-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47711 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.c.ZkStateReader Updated live nodes
> from ZooKeeper... (0) -> (3)
> [junit4] 2> 47712 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider
> Cluster at 127.0.0.1:36173/solr ready
> [junit4] 2> 47720 INFO (zkConnectionManagerCallback-370-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47721 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
> collection, keeping metrics history in memory.
> [junit4] 2> 47723 WARN (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_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> 47723 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.c.ZkStateReader Updated live nodes
> from ZooKeeper... (0) -> (3)
> [junit4] 2> 47726 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.ZkController Publish
> node=127.0.0.1:46699_solr as DOWN
> [junit4] 2> 47728 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.TransientSolrCoreCacheDefault
> Allocating transient cache for 2147483647 transient cores
> [junit4] 2> 47728 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.ZkController Register node as live
> in ZooKeeper:/live_nodes/127.0.0.1:46699_solr
> [junit4] 2> 47729 INFO (zkCallback-328-thread-2) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47729 INFO (zkCallback-344-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47729 INFO (zkCallback-339-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47729 INFO (zkCallback-356-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47729 INFO (zkCallback-329-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47735 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_34635.solr.node' (registry 'solr.node') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47738 INFO (zkCallback-367-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47742 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_34635.solr.jvm' (registry 'solr.jvm') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47742 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_34635.solr.jetty' (registry 'solr.jetty') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47743 INFO (zkCallback-369-thread-1) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
> [junit4] 2> 47743 INFO (jetty-launcher-303-thread-2)
> [n:127.0.0.1:34635_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
> definitions underneath
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node2/.
> [junit4] 2> 47750 INFO (zkConnectionManagerCallback-378-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47751 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.c.ZkStateReader Updated live nodes
> from ZooKeeper... (0) -> (4)
> [junit4] 2> 47752 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider
> Cluster at 127.0.0.1:36173/solr ready
> [junit4] 2> 47758 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
> collection, keeping metrics history in memory.
> [junit4] 2> 47770 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_46699.solr.node' (registry 'solr.node') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47777 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_46699.solr.jvm' (registry 'solr.jvm') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47777 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_46699.solr.jetty' (registry 'solr.jetty') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 47778 INFO (jetty-launcher-303-thread-3)
> [n:127.0.0.1:46699_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
> definitions underneath
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node3/.
> [junit4] 2> 47843 INFO (zkConnectionManagerCallback-384-thread-1) [
> ] o.a.s.c.c.ConnectionManager zkClient has connected
> [junit4] 2> 47845 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
> [junit4] 2> 47846 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36173/solr
> ready
> [junit4] 2> 47856 INFO (qtp1792819914-925) [n:127.0.0.1:34635_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> 47858 INFO
> (OverseerThreadFactory-166-thread-1-processing-n:127.0.0.1:37211_solr)
> [n:127.0.0.1:37211_solr ] o.a.s.c.a.c.CreateCollectionCmd Create
> collection collection1
> [junit4] 2> 47967 INFO
> (OverseerStateUpdate-72185388376784905-127.0.0.1:37211_solr-n_0000000000)
> [n:127.0.0.1:37211_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":"http://127.0.0.1:37211/solr",
> [junit4] 2> "type":"NRT",
> [junit4] 2> "waitForFinalState":"false"}
> [junit4] 2> 47970 INFO
> (OverseerStateUpdate-72185388376784905-127.0.0.1:37211_solr-n_0000000000)
> [n:127.0.0.1:37211_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":"http://127.0.0.1:37171/solr",
> [junit4] 2> "type":"NRT",
> [junit4] 2> "waitForFinalState":"false"}
> [junit4] 2> 48179 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 48187 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 48188 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> x:collection1_shard2_replica_n3] o.a.s.c.TransientSolrCoreCacheDefault
> Allocating transient cache for 2147483647 transient cores
> [junit4] 2> 49202 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
> [junit4] 2> 49210 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
> [junit4] 2> 49212 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49215 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
> [solr.TrieIntField]. Please consult documentation how to replace it
> accordingly.
> [junit4] 2> 49217 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
> [solr.TrieFloatField]. Please consult documentation how to replace it
> accordingly.
> [junit4] 2> 49218 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
> [solr.TrieLongField]. Please consult documentation how to replace it
> accordingly.
> [junit4] 2> 49219 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
> [solr.TrieDoubleField]. Please consult documentation how to replace it
> accordingly.
> [junit4] 2> 49223 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49224 WARN (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class
> [solr.TrieDateField]. Please consult documentation how to replace it
> accordingly.
> [junit4] 2> 49272 WARN (qtp1652825880-918) [n:127.0.0.1:37211_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> 49272 WARN (qtp1652825880-918) [n:127.0.0.1:37211_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> 49272 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49272 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49273 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37211.solr.core.collection1.shard1.replica_n1' (registry
> 'solr.core.collection1.shard1.replica_n1') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 49273 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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
> [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node4/collection1_shard1_replica_n1],
> dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node4/./collection1_shard1_replica_n1/data/]
> [junit4] 2> 49307 WARN (qtp546855652-933) [n:127.0.0.1:37171_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> 49307 WARN (qtp546855652-933) [n:127.0.0.1:37171_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> 49307 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49308 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49308 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.m.r.SolrJmxReporter JMX monitoring for
> 'solr_37171.solr.core.collection1.shard2.replica_n3' (registry
> 'solr.core.collection1.shard2.replica_n3') enabled at server:
> com.sun.jmx.mbeanserver.JmxMBeanServer@608efde3
> [junit4] 2> 49308 INFO (qtp546855652-933) [n:127.0.0.1:37171_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
> [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node1/collection1_shard2_replica_n3],
> dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.io.stream.MathExpressionTest_EA6F077F54B556F5-001/tempDir-001/node1/./collection1_shard2_replica_n3/data/]
> [junit4] 2> 49329 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49329 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49331 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.CommitTracker Hard AutoCommit: disabled
> [junit4] 2> 49331 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.CommitTracker Soft AutoCommit: disabled
> [junit4] 2> 49333 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.s.SolrIndexSearcher Opening [Searcher@2fdc71e4[collection1_shard1_replica_n1]
> main]
> [junit4] 2> 49334 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49334 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49334 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49335 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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 1632480425496543232
> [junit4] 2> 49337 INFO (searcherExecutor-188-thread-1-processing-n:
> 127.0.0.1:37211_solr x:collection1_shard1_replica_n1 c:collection1
> s:shard1 r:core_node2) [n:127.0.0.1:37211_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@2fdc71e4[collection1_shard1_replica_n1]
> main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 49339 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49339 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SyncStrategy Sync replicas to
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/
> [junit4] 2> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.SyncStrategy
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/ has no replicas
> [junit4] 2> 49341 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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/72185388376784905-core_node2-n_0000000000
> [junit4] 2> 49342 INFO (qtp1652825880-918) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.c.ShardLeaderElectionContext I am the new leader:
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/ shard1
> [junit4] 2> 49353 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49353 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49355 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.CommitTracker Hard AutoCommit: disabled
> [junit4] 2> 49355 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.CommitTracker Soft AutoCommit: disabled
> [junit4] 2> 49356 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.s.SolrIndexSearcher Opening [Searcher@161ef722[collection1_shard2_replica_n3]
> main]
> [junit4] 2> 49357 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49357 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49358 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49358 INFO (qtp546855652-933) [n:127.0.0.1:37171_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 1632480425520660480
> [junit4] 2> 49361 INFO (searcherExecutor-189-thread-1-processing-n:
> 127.0.0.1:37171_solr x:collection1_shard2_replica_n3 c:collection1
> s:shard2 r:core_node4) [n:127.0.0.1:37171_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@161ef722[collection1_shard2_replica_n3]
> main{ExitableDirectoryReader(UninvertingDirectoryReader())}
> [junit4] 2> 49363 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49363 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.c.SyncStrategy Sync replicas to
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/
> [junit4] 2> 49364 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49365 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.c.SyncStrategy
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/ has no replicas
> [junit4] 2> 49365 INFO (qtp546855652-933) [n:127.0.0.1:37171_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/72185388376784906-core_node4-n_0000000000
> [junit4] 2> 49366 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.c.ShardLeaderElectionContext I am the new leader:
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/ shard2
> [junit4] 2> 49467 INFO (zkCallback-328-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> 49467 INFO (zkCallback-328-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> 49467 INFO (zkCallback-329-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> 49467 INFO (zkCallback-329-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> 49468 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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> 49468 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49493 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=1314
> [junit4] 2> 49493 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=1306
> [junit4] 2> 49498 INFO (qtp1792819914-925) [n:127.0.0.1:34635_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> 49570 INFO (zkCallback-356-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> 49570 INFO (zkCallback-328-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> 49570 INFO (zkCallback-328-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> 49570 INFO (zkCallback-329-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> 49570 INFO (zkCallback-329-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> 49570 INFO (zkCallback-328-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> 49570 INFO (zkCallback-329-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> 49570 INFO (qtp1792819914-925) [n:127.0.0.1:34635_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=1714
> [junit4] 2> 49578 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish -
> collection: collection1 failOnTimeout:true timeout (sec):45
> [junit4] 2> 49579 INFO
> (SUITE-MathExpressionTest-seed#[EA6F077F54B556F5]-worker) [ ]
> o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection:
> collection1
> [junit4] 2> 49597 INFO
> (TEST-MathExpressionTest.testAddAll-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testAddAll
> [junit4] 2> 49616 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49616 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480425790144512)} 0 1
> [junit4] 2> 49618 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425793290240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49619 INFO (qtp546855652-934) [n:127.0.0.1:37171_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> 49619 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49619 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 1
> [junit4] 2> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425795387392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49620 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=*:*
> (-1632480425776513024),commit=} 0 21
> [junit4] 2> 49626 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=addAll(array(1,+2,+3),+array(4.5,+5.5,+6.5),+array(7,8,9))&wt=json&version=2.2}
> status=0 QTime=2
> [junit4] 2> 49629 INFO
> (TEST-MathExpressionTest.testAddAll-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testAddAll
> [junit4] 2> 49647 INFO
> (TEST-MathExpressionTest.testMemsetSize-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testMemsetSize
> [junit4] 2> 49652 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480425827893248)} 0 0
> [junit4] 2> 49654 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425831038976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49654 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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> 49654 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49655 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 0
> [junit4] 2> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425832087552,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49655 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=*:*
> (-1632480425824747520),commit=} 0 7
> [junit4] 2> 49660 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=let(echo%3D"b,+c",++++++++++++++a%3Dmemset(plist(tuple(field1%3Dval(1),+field2%3Dval(10)),+tuple(field1%3Dval(2),+field2%3Dval(20))),++++++++++++++++++++++++cols%3D"field1,+field2",++++++++++++++++++++++++vars%3D"f1,+f2",+++++++++++++++++++++++size%3D1),++++++++++++++b%3Dadd(f1),++++++++++++++c%3Dadd(f2))&wt=json&version=2.2}
> status=0 QTime=2
> [junit4] 2> 49662 INFO
> (TEST-MathExpressionTest.testMemsetSize-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testMemsetSize
> [junit4] 2> 49686 INFO
> (TEST-MathExpressionTest.testOnes-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testOnes
> [junit4] 2> 49709 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480425878224896)} 0 10
> [junit4] 2> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425890807808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_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> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49711 INFO (qtp546855652-932) [n:127.0.0.1:37171_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=
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 0
> [junit4] 2> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425891856384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49712 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=*:*
> (-1632480425876127744),commit=} 0 15
> [junit4] 2> 49733 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=ones(6)&wt=json&version=2.2} status=0
> QTime=0
> [junit4] 2> 49734 INFO
> (TEST-MathExpressionTest.testOnes-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testOnes
> [junit4] 2> 49783 INFO
> (TEST-MathExpressionTest.testSumDifference-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testSumDifference
> [junit4] 2> 49787 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480425969451008)} 0 1
> [junit4] 2> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425973645312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49790 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 0
> [junit4] 2> 49791 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480425974693888,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49791 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 49792 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49792 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=*:*
> (-1632480425968402432),commit=} 0 7
> [junit4] 2> 49808 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=sumDifference(array(2,4,6,8,10,12),array(1,2,3,4,5,6))&wt=json&version=2.2}
> status=0 QTime=1
> [junit4] 2> 49834 INFO
> (TEST-MathExpressionTest.testSumDifference-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testSumDifference
> [junit4] 2> 49863 INFO
> (OverseerCollectionConfigSetProcessor-72185388376784905-127.0.0.1:37211_solr-n_0000000000)
> [n:127.0.0.1:37211_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> 49892 INFO
> (TEST-MathExpressionTest.testExponentialMovingAverage-seed#[EA6F077F54B556F5])
> [ ] o.a.s.SolrTestCaseJ4 ###Starting testExponentialMovingAverage
> [junit4] 2> 49901 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480426088988672)} 0 0
> [junit4] 2> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426092134400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49903 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 0
> [junit4] 2> 49903 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426092134400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 49903 INFO (qtp546855652-934) [n:127.0.0.1:37171_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> 49904 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 49904 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=*:*
> (-1632480426087940096),commit=} 0 4
> [junit4] 2> 49930 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=expMovingAvg(array(22.27,+22.19,+22.08,+22.17,+22.18,+22.13,+22.23,+22.43,+22.24,+22.29,+22.15,+22.39,+22.38,+22.61,+23.36,+24.05,+23.75,+23.83,+23.95,+23.63,+23.82,+23.87,+23.65,+23.19,23.10,+23.33,+22.68,+23.10,+22.40,+22.17),+10)&wt=json&version=2.2}
> status=0 QTime=1
> [junit4] 2> 49943 INFO
> (TEST-MathExpressionTest.testExponentialMovingAverage-seed#[EA6F077F54B556F5])
> [ ] o.a.s.SolrTestCaseJ4 ###Ending testExponentialMovingAverage
> [junit4] 2> 49996 INFO
> (TEST-MathExpressionTest.testConvexHull-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testConvexHull
> [junit4] 2> 50002 INFO (qtp546855652-932) [n:127.0.0.1:37171_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=
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480426193846272)} 0 2
> [junit4] 2> 50004 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426198040576,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 50004 INFO (qtp546855652-933) [n:127.0.0.1:37171_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> 50005 INFO (qtp546855652-933) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 50005 INFO (qtp546855652-933) [n:127.0.0.1:37171_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=
> http://127.0.0.1:37211/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 1
> [junit4] 2> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426201186304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 50007 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=*:*
> (-1632480426191749120),commit=} 0 9
> [junit4] 2> 50039 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(echo%3Dtrue,++++++++++++++x%3Darray(96.42894739701268,+99.11076410926444,+95.71563821370013,101.4356840561301,+96.17912865782684,+113.430677406492,+109.5927785287056,+87.26561260238425,+103.3122002816537,+100.4959815617706,+92.78972440872515,+92.98815024042877,+89.1448359089767,+104.9410622701036,+106.5546761317927,+102.0132643274808,+119.6726096270366,+97.61388415294184,+106.7928221374049,+94.31369945729962,+87.37098859879977,+82.8015657665458,+88.84342877874248,+94.58797342988339,+92.38720473619748),++++++++++++++y%3Darray(97.43395922838836,+109.5441846957560,+78.82698890096127,+96.67181538737611,95.52423701473863,+85.3391529394878,+87.01956497912255,+111.5289690656729,86.41034184809114,+84.11696923489203,+109.3874354244069,+102.3391063812790,109.0604436531823,102.7957014900897,114.4376483055848,107.4387578165579,106.2490201384653,103.4490197583837,93.8201540211101,101.6060721649409,+115.3512636715722,119.1046170610335,99.74910277836263,104.2116724112481,+86.02222520549304),++++++++++++++c%3Dtranspose(matrix(x,+y)),++++++++++++++d%3DconvexHull(c),++++++++++++++e%3DgetVertices(d),++++++++++++++f%3DgetArea(d),++++++++++++++g%3DgetBoundarySize(d),++++++++++++++h%3DgetBaryCenter(d))&wt=json&version=2.2}
> status=0 QTime=3
> [junit4] 2> 50103 INFO
> (TEST-MathExpressionTest.testConvexHull-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testConvexHull
> [junit4] 2> 50143 INFO
> (TEST-MathExpressionTest.testGaussfit-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testGaussfit
> [junit4] 2> 50146 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480426346938368)} 0 0
> [junit4] 2> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426349035520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 50148 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 0
> [junit4] 2> 50148 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426349035520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 50148 INFO (qtp546855652-934) [n:127.0.0.1:37171_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> 50149 INFO (qtp546855652-934) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 50149 INFO (qtp546855652-934) [n:127.0.0.1:37171_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=*:*
> (-1632480426344841216),commit=} 0 5
> [junit4] 2> 50152 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(echo%3Dtrue,+x%3Darray(79.56,81.32,82.82,84.64,86.18,87.89,89.53,91.14,92.8,94.43,96.08,97.72,99.37,101,102.66,104.3,105.94,107.59,109.23,110.87,112.52,114.13,115.82,117.44,119.27),+y%3Darray(3,+3,+26,+54,+139,+344,+685,+1289,+2337,+3593,+4781,+5964,+6538,+6357,+5705,+4548,+3280,+2058,+1191,+649,+285,+112,+34,+18,+7),g%3Dgaussfit(x,y))&wt=json&version=2.2}
> status=0 QTime=1
> [junit4] 2> 50173 INFO
> (TEST-MathExpressionTest.testGaussfit-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testGaussfit
> [junit4] 2> 50194 INFO
> (TEST-MathExpressionTest.testZplot-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Starting testZplot
> [junit4] 2> 50196 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:*
> (-1632480426399367168)} 0 0
> [junit4] 2> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426401464320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_solr
> c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 50198 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=
> http://127.0.0.1:37171/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
> 0 0
> [junit4] 2> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 start
> commit{_version_=1632480426401464320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
> [junit4] 2> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_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> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_solr
> c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3]
> o.a.s.u.DirectUpdateHandler2 end_commit_flush
> [junit4] 2> 50198 INFO (qtp546855652-932) [n:127.0.0.1:37171_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=*:*
> (-1632480426398318592),commit=} 0 3
> [junit4] 2> 50202 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(c%3Dtuple(a%3Dadd(1,2),+b%3Dadd(2,3)),+++++++++++++++zplot(table%3Dc))&wt=json&version=2.2}
> status=0 QTime=0
> [junit4] 2> 50205 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=let(c%3Dlist(tuple(a%3Dadd(1,2),+b%3Dadd(2,3)),+tuple(a%3Dadd(1,3),+b%3Dadd(2,4))),++++++++zplot(table%3Dc))&wt=json&version=2.2}
> status=0 QTime=0
> [junit4] 2> 50207 INFO (qtp1652825880-918) [n:127.0.0.1:37211_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=let(a%3Darray(1,2,3,4),++++++++b%3Darray(10,11,12,13),++++++++zplot(x%3Da,+y%3Db))&wt=json&version=2.2}
> status=0 QTime=0
> [junit4] 2> 50210 INFO (qtp1652825880-923) [n:127.0.0.1:37211_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=zplot(dist%3DbinomialDistribution(10,+.50))&wt=json&version=2.2}
> status=0 QTime=0
> [junit4] 2> 50327 INFO (qtp1652825880-914) [n:127.0.0.1:37211_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=zplot(dist%3DnormalDistribution(100,+10))&wt=json&version=2.2}
> status=0 QTime=0
> [junit4] 2> 50455 INFO
> (TEST-MathExpressionTest.testZplot-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCaseJ4 ###Ending testZplot
> [junit4] 2> 50475 INFO
> (TEST-MathExpressionTest.testFreqTable-seed#[EA6F077F54B556F5]) [ ]
> o.a.s.SolrTestCase
>
> [...truncated too long message...]
>
> ^^^^^^^^^^^^^^^^^^
> [ecj-lint] Resource leak: 'queryRequest' is not closed at this location
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 4. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 19)
> [ecj-lint] import javax.naming.Context;
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] The type javax.naming.Context is not accessible
> [ecj-lint] ----------
> [ecj-lint] 5. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 20)
> [ecj-lint] import javax.naming.InitialContext;
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] The type javax.naming.InitialContext is not accessible
> [ecj-lint] ----------
> [ecj-lint] 6. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 21)
> [ecj-lint] import javax.naming.NamingException;
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] The type javax.naming.NamingException is not accessible
> [ecj-lint] ----------
> [ecj-lint] 7. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 22)
> [ecj-lint] import javax.naming.NoInitialContextException;
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] The type javax.naming.NoInitialContextException is not
> accessible
> [ecj-lint] ----------
> [ecj-lint] 8. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 776)
> [ecj-lint] Context c = new InitialContext();
> [ecj-lint] ^^^^^^^
> [ecj-lint] Context cannot be resolved to a type
> [ecj-lint] ----------
> [ecj-lint] 9. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 776)
> [ecj-lint] Context c = new InitialContext();
> [ecj-lint] ^^^^^^^^^^^^^^
> [ecj-lint] InitialContext cannot be resolved to a type
> [ecj-lint] ----------
> [ecj-lint] 10. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 779)
> [ecj-lint] } catch (NoInitialContextException e) {
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] NoInitialContextException cannot be resolved to a type
> [ecj-lint] ----------
> [ecj-lint] 11. ERROR in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/core/SolrResourceLoader.java
> (at line 781)
> [ecj-lint] } catch (NamingException e) {
> [ecj-lint] ^^^^^^^^^^^^^^^
> [ecj-lint] NamingException cannot be resolved to a type
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 12. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/handler/admin/SegmentsInfoRequestHandler.java
> (at line 215)
> [ecj-lint] leafReader = ((FilterLeafReader)leafReader).getDelegate();
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] Resource leak: 'leafReader' is not closed at this location
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 13. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/handler/component/HttpShardHandler.java
> (at line 142)
> [ecj-lint] return new JavaBinCodec(null,
> stringCache).setReadStringAsCharSeq(true);
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 14. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/handler/loader/JavabinLoader.java
> (at line 137)
> [ecj-lint] new JavaBinCodec() {
> [ecj-lint] SolrParams params;
> [ecj-lint] AddUpdateCommand addCmd = null;
> [ecj-lint]
> [ecj-lint] @Override
> [ecj-lint] public List<Object> readIterator(DataInputInputStream
> fis) throws IOException {
> [ecj-lint] while (true) {
> [ecj-lint] Object o = readVal(fis);
> [ecj-lint] if (o == END_OBJ) break;
> [ecj-lint] if (o instanceof NamedList) {
> [ecj-lint] params = ((NamedList) o).toSolrParams();
> [ecj-lint] } else {
> [ecj-lint] try {
> [ecj-lint] if (o instanceof byte[]) {
> [ecj-lint] if (params != null) req.setParams(params);
> [ecj-lint] byte[] buf = (byte[]) o;
> [ecj-lint] contentStreamLoader.load(req, rsp, new
> ContentStreamBase.ByteArrayStream(buf, null), processor);
> [ecj-lint] } else {
> [ecj-lint] throw new RuntimeException("unsupported type
> ");
> [ecj-lint] }
> [ecj-lint] } catch (Exception e) {
> [ecj-lint] throw new RuntimeException(e);
> [ecj-lint] } finally {
> [ecj-lint] params = null;
> [ecj-lint] req.setParams(old);
> [ecj-lint] }
> [ecj-lint] }
> [ecj-lint] }
> [ecj-lint] return Collections.emptyList();
> [ecj-lint] }
> [ecj-lint]
> [ecj-lint] }.unmarshal(in);
> [ecj-lint]
> ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 15. INFO in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/logging/log4j2/Log4j2Watcher.java
> (at line 187)
> [ecj-lint] if (logger == root || root.equals(logger) ||
> isRootLogger(name) || "".equals(name)) {
> [ecj-lint] ^^^^^^
> [ecj-lint] Unlikely argument type for equals():
> Map.Entry<String,LoggerConfig> seems to be unrelated to Logger
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 16. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/update/SolrIndexSplitter.java
> (at line 258)
> [ecj-lint] HardlinkCopyDirectoryWrapper hardLinkedDir = new
> HardlinkCopyDirectoryWrapper(splitDir);
> [ecj-lint] ^^^^^^^^^^^^^
> [ecj-lint] Resource leak: 'hardLinkedDir' is never closed
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 17. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/util/FileUtils.java
> (at line 50)
> [ecj-lint] in = new FileInputStream(src).getChannel();
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
> [ecj-lint] ----------
> [ecj-lint] 18. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/util/FileUtils.java
> (at line 51)
> [ecj-lint] out = new FileOutputStream(destination).getChannel();
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
> [ecj-lint] ----------
> [ecj-lint] ----------
> [ecj-lint] 19. WARNING in
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/java/org/apache/solr/util/TestInjection.java
> (at line 259)
> [ecj-lint] timers.remove(this);
> [ecj-lint] ^^^^
> [ecj-lint] Unlikely argument type new TimerTask(){} for remove(Object) on
> a Collection<Timer>
> [ecj-lint] ----------
> [ecj-lint] 19 problems (8 errors, 10 warnings, 1 info)
>
> BUILD FAILED
> /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:634: The
> following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:101: The
> following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:681: The
> following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:2010:
> The following error occurred while executing this line:
> /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:2049:
> Compile failed; see the compiler error output for details.
>
> Total time: 77 minutes 56 seconds
> Build step 'Invoke Ant' marked build as failure
> Archiving artifacts
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> [WARNINGS] Skipping publisher since build result is FAILURE
> Recording test results
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> Email was triggered for: Failure - Any
> Sending email for trigger: Failure - Any
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
> Setting
> ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org