You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/03/05 02:59:32 UTC
[JENKINS-EA] Lucene-Solr-8.x-Linux (64bit/jdk-15-ea+9) - Build #
2357 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2357/
Java: 64bit/jdk-15-ea+9 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations
Error Message:
expected:<14> but was:<8>
Stack Trace:
java.lang.AssertionError: expected:<14> but was:<8>
at __randomizedtesting.SeedInfo.seed([CC010DA93BEE549F:B937FEFC6A2EF49]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations(TestSkipOverseerOperations.java:191)
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:564)
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:832)
Build Log:
[...truncated 13925 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSkipOverseerOperations
[junit4] 2> 251152 INFO (SUITE-TestSkipOverseerOperations-seed#[CC010DA93BEE549F]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 251152 INFO (SUITE-TestSkipOverseerOperations-seed#[CC010DA93BEE549F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 251153 INFO (SUITE-TestSkipOverseerOperations-seed#[CC010DA93BEE549F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/data-dir-28-001
[junit4] 2> 251153 WARN (SUITE-TestSkipOverseerOperations-seed#[CC010DA93BEE549F]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 251153 INFO (SUITE-TestSkipOverseerOperations-seed#[CC010DA93BEE549F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 251154 INFO (SUITE-TestSkipOverseerOperations-seed#[CC010DA93BEE549F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 251155 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSkipLeaderOperations
[junit4] 2> 251156 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001
[junit4] 2> 251156 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 251156 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 251156 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 251256 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer start zk server on port:38975
[junit4] 2> 251256 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38975
[junit4] 2> 251256 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38975
[junit4] 2> 251256 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38975
[junit4] 2> 251258 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251258 INFO (zkConnectionManagerCallback-4092-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251258 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251270 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251272 INFO (zkConnectionManagerCallback-4094-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251272 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251273 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251278 INFO (zkConnectionManagerCallback-4096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251278 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251383 WARN (jetty-launcher-4097-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 251383 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 251383 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 251383 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+9-264
[junit4] 2> 251384 WARN (jetty-launcher-4097-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 251384 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 251384 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 251384 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+9-264
[junit4] 2> 251385 WARN (jetty-launcher-4097-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 251385 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 251385 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 251385 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+9-264
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 251395 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 251396 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@591ba08{/solr,null,AVAILABLE}
[junit4] 2> 251396 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@42008bef{/solr,null,AVAILABLE}
[junit4] 2> 251397 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f3d57c6{/solr,null,AVAILABLE}
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@368083eb{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٣٧٠٥٣}
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.e.j.s.Server Started @٢٥١٤١٧ms
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37053}
[junit4] 2> 251398 ERROR (jetty-launcher-4097-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 251398 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T02:36:15.885476Z
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5818111f{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٤١٤٠٧}
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.e.j.s.Server Started @٢٥١٤٢١ms
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41407}
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7a49e151{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٤٥٨١١}
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.e.j.s.Server Started @٢٥١٤٢١ms
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45811}
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251402 ERROR (jetty-launcher-4097-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 251402 ERROR (jetty-launcher-4097-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T02:36:15.889347Z
[junit4] 2> 251402 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T02:36:15.889346Z
[junit4] 2> 251403 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251403 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251404 INFO (zkConnectionManagerCallback-4099-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251404 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251404 INFO (zkConnectionManagerCallback-4102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251404 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251404 INFO (zkConnectionManagerCallback-4103-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251404 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251404 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 251406 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 251406 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 251636 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 251637 WARN (jetty-launcher-4097-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51457cd3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251637 WARN (jetty-launcher-4097-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51457cd3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251639 WARN (jetty-launcher-4097-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5647c8e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251639 WARN (jetty-launcher-4097-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5647c8e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251640 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38975/solr
[junit4] 2> 251640 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251641 INFO (zkConnectionManagerCallback-4111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251641 INFO (jetty-launcher-4097-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251662 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 251663 WARN (jetty-launcher-4097-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@167d018e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251663 WARN (jetty-launcher-4097-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@167d018e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251666 WARN (jetty-launcher-4097-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@47244612[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251666 WARN (jetty-launcher-4097-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@47244612[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251667 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38975/solr
[junit4] 2> 251668 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251668 INFO (zkConnectionManagerCallback-4117-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251668 INFO (jetty-launcher-4097-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251812 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251812 INFO (zkConnectionManagerCallback-4120-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251812 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251815 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 251817 WARN (jetty-launcher-4097-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2c70ed2b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251817 WARN (jetty-launcher-4097-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2c70ed2b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251819 WARN (jetty-launcher-4097-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@66baeb08[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251819 WARN (jetty-launcher-4097-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@66baeb08[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 251819 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38975/solr
[junit4] 2> 251820 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251820 INFO (zkConnectionManagerCallback-4128-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251820 INFO (jetty-launcher-4097-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251846 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41407_solr
[junit4] 2> 251846 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.Overseer Overseer (id=72122945488879624-127.0.0.1:41407_solr-n_0000000000) starting
[junit4] 2> 251849 INFO (OverseerStateUpdate-72122945488879624-127.0.0.1:41407_solr-n_0000000000) [n:127.0.0.1:41407_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41407_solr
[junit4] 2> 251849 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41407_solr
[junit4] 2> 251849 INFO (zkCallback-4119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 251850 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 251850 WARN (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 251859 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 251870 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41407.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 251874 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41407.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 251874 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41407.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 251875 INFO (jetty-launcher-4097-thread-1) [n:127.0.0.1:41407_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node1/.
[junit4] 2> 251912 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 251918 INFO (zkConnectionManagerCallback-4132-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 251918 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 251920 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 251922 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37053_solr as DOWN
[junit4] 2> 251922 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 251922 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37053_solr
[junit4] 2> 251923 INFO (zkCallback-4119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 251923 INFO (zkCallback-4131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 251923 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 251923 WARN (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 251931 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 251941 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37053.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 251952 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37053.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 251953 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37053.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 251954 INFO (jetty-launcher-4097-thread-2) [n:127.0.0.1:37053_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node2/.
[junit4] 2> 252012 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 252013 INFO (zkConnectionManagerCallback-4138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 252013 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 252014 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 252016 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45811_solr as DOWN
[junit4] 2> 252016 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 252016 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45811_solr
[junit4] 2> 252017 INFO (zkCallback-4119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 252017 INFO (zkCallback-4137-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 252017 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 252018 WARN (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 252019 INFO (zkCallback-4131-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 252025 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 252037 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 252043 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 252043 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 252044 INFO (jetty-launcher-4097-thread-3) [n:127.0.0.1:45811_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node3/.
[junit4] 2> 252060 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 252061 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 252061 INFO (zkConnectionManagerCallback-4147-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 252061 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 252062 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 252062 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38975/solr ready
[junit4] 2> 252077 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 252080 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 252082 INFO (qtp900924332-6325) [n:127.0.0.1:45811_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&tlogReplicas=0&createNodeSet=127.0.0.1:37053_solr,127.0.0.1:45811_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 252083 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 252083 INFO (OverseerCollectionConfigSetProcessor-72122945488879624-127.0.0.1:41407_solr-n_0000000000) [n:127.0.0.1:41407_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> 252083 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/collection1.AUTOCREATED/protwords.txt
[junit4] 2> 252084 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 252084 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/collection1.AUTOCREATED/solrconfig.xml
[junit4] 2> 252085 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/collection1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 252085 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/collection1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 252086 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 252086 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/collection1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 252087 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/collection1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 252087 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/collection1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 252088 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/collection1.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 252088 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 252089 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 252089 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/collection1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 252089 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 252090 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/collection1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 252090 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 252091 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/collection1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 252091 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 252091 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 252092 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 252092 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/collection1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 252093 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/collection1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 252093 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/collection1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 252094 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/collection1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 252094 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/collection1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 252094 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/collection1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 252095 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/collection1.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 252095 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 252096 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/collection1.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 252096 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 252097 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/collection1.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 252097 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/collection1.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 252098 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/collection1.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 252098 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/collection1.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 252099 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/collection1.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 252099 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/collection1.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 252099 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/collection1.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 252100 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 252100 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/collection1.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 252101 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/collection1.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 252101 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/collection1.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 252101 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/collection1.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 252102 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/collection1.AUTOCREATED/synonyms.txt
[junit4] 2> 252102 INFO (OverseerThreadFactory-808-thread-2-processing-n:127.0.0.1:41407_solr) [n:127.0.0.1:41407_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/collection1.AUTOCREATED/stopwords.txt
[junit4] 2> 252206 INFO (OverseerStateUpdate-72122945488879624-127.0.0.1:41407_solr-n_0000000000) [n:127.0.0.1:41407_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:37053/solr",
[junit4] 2> "node_name":"127.0.0.1:37053_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 252207 INFO (OverseerStateUpdate-72122945488879624-127.0.0.1:41407_solr-n_0000000000) [n:127.0.0.1:41407_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_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45811/solr",
[junit4] 2> "node_name":"127.0.0.1:45811_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 252424 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=collection1.AUTOCREATED&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 252425 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=collection1.AUTOCREATED&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 252436 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 252436 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 252466 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=default-config
[junit4] 2> 252470 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=default-config
[junit4] 2> 252598 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 252603 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 252629 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset collection1.AUTOCREATED, trusted=true
[junit4] 2> 252630 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37053.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 252630 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node2/collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
[junit4] 2> 252637 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from configset collection1.AUTOCREATED, trusted=true
[junit4] 2> 252638 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45811.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4ffc8620
[junit4] 2> 252638 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node3/collection1_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001/tempDir-001/node3/./collection1_shard2_replica_n2/data/]
[junit4] 2> 252684 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 252684 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 252685 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 252685 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 252686 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 252686 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 252687 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bd63e16[collection1_shard1_replica_n1] main]
[junit4] 2> 252687 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 252687 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 252688 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/collection1.AUTOCREATED
[junit4] 2> 252688 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/collection1.AUTOCREATED
[junit4] 2> 252688 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 252688 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 252688 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 252688 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 252689 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 252689 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1660289838928101376
[junit4] 2> 252689 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@465d79d6[collection1_shard2_replica_n2] main]
[junit4] 2> 252690 INFO (searcherExecutor-821-thread-1-processing-n:127.0.0.1:37053_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@2bd63e16[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 252690 INFO (searcherExecutor-821-thread-1-processing-n:127.0.0.1:37053_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 252690 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/collection1.AUTOCREATED
[junit4] 2> 252690 INFO (searcherExecutor-821-thread-1-processing-n:127.0.0.1:37053_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 252690 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/collection1.AUTOCREATED
[junit4] 2> 252690 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/collection1.AUTOCREATED/managed-schema
[junit4] 2> 252691 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 252691 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 252691 INFO (searcherExecutor-821-thread-1-processing-n:127.0.0.1:37053_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@2bd63e16[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 252691 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 252692 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 252692 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1660289838931247104
[junit4] 2> 252692 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 252692 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 252693 INFO (searcherExecutor-822-thread-1-processing-n:127.0.0.1:45811_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@465d79d6[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 252693 INFO (searcherExecutor-822-thread-1-processing-n:127.0.0.1:45811_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 252693 INFO (searcherExecutor-822-thread-1-processing-n:127.0.0.1:45811_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 252693 INFO (searcherExecutor-822-thread-1-processing-n:127.0.0.1:45811_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@465d79d6[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 252694 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 252694 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 252694 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:37053/solr/collection1_shard1_replica_n1/
[junit4] 2> 252694 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 252694 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:37053/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 252694 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 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/72122945488879626-core_node3-n_0000000000
[junit4] 2> 252694 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 252694 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 252695 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:37053/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 252695 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 252695 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 252695 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45811/solr/collection1_shard2_replica_n2/
[junit4] 2> 252696 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 252696 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:45811/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 252696 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72122945488879627-core_node4-n_0000000000
[junit4] 2> 252696 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45811/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 252797 INFO (zkCallback-4137-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: [3])
[junit4] 2> 252797 INFO (zkCallback-4131-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: [3])
[junit4] 2> 252797 INFO (zkCallback-4137-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: [3])
[junit4] 2> 252797 INFO (zkCallback-4131-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: [3])
[junit4] 2> 252797 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 252798 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 252800 INFO (qtp900924332-6323) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=collection1.AUTOCREATED&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=376
[junit4] 2> 252800 INFO (qtp2038069391-6331) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=collection1.AUTOCREATED&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=374
[junit4] 2> 252801 INFO (qtp900924332-6325) [n:127.0.0.1:45811_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> 252900 INFO (zkCallback-4131-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: [3])
[junit4] 2> 252900 INFO (zkCallback-4131-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: [3])
[junit4] 2> 252900 INFO (zkCallback-4137-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: [3])
[junit4] 2> 252900 INFO (zkCallback-4137-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: [3])
[junit4] 2> 252900 INFO (zkCallback-4131-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: [3])
[junit4] 2> 252900 INFO (zkCallback-4137-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: [3])
[junit4] 2> 252901 INFO (qtp900924332-6325) [n:127.0.0.1:45811_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&tlogReplicas=0&createNodeSet=127.0.0.1:37053_solr,127.0.0.1:45811_solr&wt=javabin&version=2} status=0 QTime=818
[junit4] 2> 252901 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: collection1
[junit4] 2> 252902 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2118385842
[junit4] 2> 252902 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37053_solr
[junit4] 2> 252902 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 252902 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:37053_solr as DOWN
[junit4] 2> 252903 INFO (coreCloseExecutor-831-thread-1) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@7f561bb1
[junit4] 2> 252903 INFO (coreCloseExecutor-831-thread-1) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard1.replica_n1, tag=SolrCore@7f561bb1
[junit4] 2> 252903 INFO (coreCloseExecutor-831-thread-1) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1182cd01: rootName = solr_37053, domain = solr.core.collection1.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@3184d530
[junit4] 2> 252908 INFO (coreCloseExecutor-831-thread-1) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard1.leader, tag=SolrCore@7f561bb1
[junit4] 2> 252909 INFO (coreCloseExecutor-831-thread-1) [n:127.0.0.1:37053_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 252910 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 252910 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e0ee194: rootName = solr_37053, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4de06974
[junit4] 2> 252913 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 252913 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60fc5c2c: rootName = solr_37053, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e75624b
[junit4] 2> 252915 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 252915 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@611dc7d0: rootName = solr_37053, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@68e04064
[junit4] 2> 252915 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 252916 INFO (zkCallback-4119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 252916 INFO (zkCallback-4137-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 252916 INFO (zkCallback-4146-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 253003 INFO (zkCallback-4137-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: [2])
[junit4] 2> 253003 INFO (zkCallback-4137-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: [2])
[junit4] 2> 253003 INFO (zkCallback-4137-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: [2])
[junit4] 2> 253017 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@368083eb{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٠}
[junit4] 2> 253017 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@591ba08{/solr,null,UNAVAILABLE}
[junit4] 2> 253017 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 253018 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=943337935
[junit4] 2> 253018 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45811_solr
[junit4] 2> 253018 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 253018 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:45811_solr as DOWN
[junit4] 2> 253019 INFO (coreCloseExecutor-833-thread-1) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@34f0bd53
[junit4] 2> 253019 INFO (coreCloseExecutor-833-thread-1) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1.shard2.replica_n2, tag=SolrCore@34f0bd53
[junit4] 2> 253019 INFO (coreCloseExecutor-833-thread-1) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@593fedc0: rootName = solr_45811, domain = solr.core.collection1.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@7286e874
[junit4] 2> 253024 INFO (coreCloseExecutor-833-thread-1) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader, tag=SolrCore@34f0bd53
[junit4] 2> 253025 INFO (coreCloseExecutor-833-thread-1) [n:127.0.0.1:45811_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 253026 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 253026 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@34fd18b: rootName = solr_45811, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5af22052
[junit4] 2> 253030 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 253030 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36899e71: rootName = solr_45811, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e75624b
[junit4] 2> 253031 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 253031 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4faf811c: rootName = solr_45811, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@68e04064
[junit4] 2> 253031 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 253032 INFO (zkCallback-4146-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 253032 INFO (zkCallback-4119-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 253133 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7a49e151{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٠}
[junit4] 2> 253133 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3f3d57c6{/solr,null,UNAVAILABLE}
[junit4] 2> 253133 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 253134 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 37053
[junit4] 2> 253134 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 45811
[junit4] 2> 253134 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.SolrCloudTestCase waitForState (collection1): Expected single liveNode
[junit4] 2> 253146 INFO (qtp400750504-6366) [n:127.0.0.1:41407_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 253150 INFO (OverseerCollectionConfigSetProcessor-72122945488879624-127.0.0.1:41407_solr-n_0000000000) [n:127.0.0.1:41407_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 253151 INFO (qtp400750504-6366) [n:127.0.0.1:41407_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 253151 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=37053)
[junit4] 2> 253154 WARN (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 253154 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 37053 try number 1 ...
[junit4] 2> 253154 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+9-264
[junit4] 2> 253155 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 253156 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 253156 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 253156 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5282b622{/solr,null,AVAILABLE}
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e807e9c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٣٧٠٥٣}
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.s.Server Started @٢٥٣١٧٧ms
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37053}
[junit4] 2> 253157 ERROR (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 253157 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T02:36:17.644863Z
[junit4] 2> 253158 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 253159 INFO (zkConnectionManagerCallback-4159-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 253159 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 253159 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 253294 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 253295 WARN (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@714db486[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 253295 WARN (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@714db486[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 253297 WARN (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@454cbbb7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 253297 WARN (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@454cbbb7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 253298 INFO (TEST-TestSkipOverseerOperations.testSkipLeaderOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38975/s
[...truncated too long message...]
shard1 r:core_node5 x:collection2_shard1_replica_n2 ] o.a.s.c.ZkContainer :org.apache.solr.common.SolrException: Could not load collection from ZK: collection2
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1553)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:798)
[junit4] 2> at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:145)
[junit4] 2> at org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:129)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.waitForReplicasToComeUp(ElectionContext.java:648)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:342)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:172)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:137)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:309)
[junit4] 2> at org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1469)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1209)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1160)
[junit4] 2> at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:191)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection2/state.json
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:2131)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:338)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:338)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1565)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1551)
[junit4] 2> ... 16 more
[junit4] 2>
[junit4] 2> 259775 INFO (jetty-closer-4306-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@64d5c82e{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٣٣١٨٥}
[junit4] 2> 259775 INFO (jetty-closer-4306-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@738b6197{/solr,null,UNAVAILABLE}
[junit4] 2> 259775 INFO (jetty-closer-4306-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 260358 INFO (jetty-closer-4306-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1d39735e{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:٣٨٨٣٣}
[junit4] 2> 260358 INFO (jetty-closer-4306-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@72aecbd4{/solr,null,UNAVAILABLE}
[junit4] 2> 260358 INFO (jetty-closer-4306-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 260359 INFO (TEST-TestSkipOverseerOperations.testSkipDownOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 260561 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/configs/collection2.AUTOCREATED/managed-schema
[junit4] 2> 7 /solr/collections/collection2/terms/shard1
[junit4] 2> 7 /solr/collections/collection2/terms/shard2
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 4 /solr/configs/collection2.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 33 /solr/collections/collection2/state.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 3 /solr/overseer_elect/election/72122945751941129-127.0.0.1:35967_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/live_nodes
[junit4] 2> 25 /solr/overseer/queue
[junit4] 2> 15 /solr/overseer/collection-queue-work
[junit4] 2> 10 /solr/collections
[junit4] 2>
[junit4] 2> 260562 INFO (TEST-TestSkipOverseerOperations.testSkipDownOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:39341
[junit4] 2> 260562 INFO (TEST-TestSkipOverseerOperations.testSkipDownOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39341
[junit4] 2> 260562 INFO (TEST-TestSkipOverseerOperations.testSkipDownOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39341
[junit4] 2> 260562 INFO (TEST-TestSkipOverseerOperations.testSkipDownOperations-seed#[CC010DA93BEE549F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSkipDownOperations
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSkipOverseerOperations -Dtests.method=testSkipDownOperations -Dtests.seed=CC010DA93BEE549F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SO -Dtests.timezone=Asia/Khandyga -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 5.39s J4 | TestSkipOverseerOperations.testSkipDownOperations <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<14> but was:<8>
[junit4] > at __randomizedtesting.SeedInfo.seed([CC010DA93BEE549F:B937FEFC6A2EF49]:0)
[junit4] > at org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations(TestSkipOverseerOperations.java:191)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J4/temp/solr.cloud.TestSkipOverseerOperations_CC010DA93BEE549F-001
[junit4] 2> Mar 05, 2020 2:36:25 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1466, maxMBSortInHeap=7.791023748045136, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6f92ad7), locale=ar-SO, timezone=Asia/Khandyga
[junit4] 2> NOTE: Linux 5.3.0-40-generic amd64/Oracle Corporation 15-ea (64-bit)/cpus=16,threads=1,free=209972536,total=507510784
[junit4] 2> NOTE: All tests run in this JVM: [DistributedQueryElevationComponentTest, TestNamedUpdateProcessors, ZkSolrClientTest, SolrRrdBackendFactoryTest, TestDynamicURP, TestConfig, RootFieldTest, SolrGraphiteReporterTest, TestSQLHandler, SplitByPrefixTest, ExplicitHLLTest, BadCopyFieldTest, SchemaApiFailureTest, CategoryRoutedAliasUpdateProcessorTest, MetricTriggerTest, TestImpersonationWithHadoopAuth, WordBreakSolrSpellCheckerTest, TestTrackingShardHandlerFactory, TestCustomDocTransformer, HdfsBasicDistributedZk2Test, TestPrepRecovery, VMParamsZkACLAndCredentialsProvidersTest, TestCloudInspectUtil, JavaBinAtomicUpdateMultivalueTest, HealthCheckHandlerTest, SolrInfoBeanTest, TestSkipOverseerOperations]
[junit4] Completed [172/901 (1!)] on J4 in 10.26s, 2 tests, 1 failure <<< FAILURES!
[...truncated 47264 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2357/consoleText
[repro] Revision: 1573d3d2d83c5008dd179736feb7218906830fb0
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestSkipOverseerOperations -Dtests.method=testSkipDownOperations -Dtests.seed=CC010DA93BEE549F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SO -Dtests.timezone=Asia/Khandyga -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestSkipOverseerOperations
[repro] ant compile-test
[...truncated 3423 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSkipOverseerOperations" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=CC010DA93BEE549F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SO -Dtests.timezone=Asia/Khandyga -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.TestSkipOverseerOperations
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS] Lucene-Solr-8.x-Linux (32bit/jdk1.8.0_201) - Build # 2359
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2359/
Java: 32bit/jdk1.8.0_201 -client -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.lucene.index.TestIndexWriterMergePolicy.testMergeOnCommit
Error Message:
expected:<1> but was:<4>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<4>
at __randomizedtesting.SeedInfo.seed([2375C8C036BF4B21:7F3D2C7D75AA901]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.lucene.index.TestIndexWriterMergePolicy.testMergeOnCommit(TestIndexWriterMergePolicy.java:400)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.lucene.index.TestIndexWriterMergePolicy.testMergeOnCommit
Error Message:
expected:<1> but was:<6>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<6>
at __randomizedtesting.SeedInfo.seed([2375C8C036BF4B21:7F3D2C7D75AA901]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.lucene.index.TestIndexWriterMergePolicy.testMergeOnCommit(TestIndexWriterMergePolicy.java:400)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 1085 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterMergePolicy
[junit4] IGNOR/A 0.00s J4 | TestIndexWriterMergePolicy.testMaxBufferedDocsChange
[junit4] > Assumption #1: 'nightly' test group is disabled (@Nightly())
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexWriterMergePolicy -Dtests.method=testMergeOnCommit -Dtests.seed=2375C8C036BF4B21 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr -Dtests.timezone=Mexico/BajaSur -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 44.9s J4 | TestIndexWriterMergePolicy.testMergeOnCommit <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<4>
[junit4] > at __randomizedtesting.SeedInfo.seed([2375C8C036BF4B21:7F3D2C7D75AA901]:0)
[junit4] > at org.apache.lucene.index.TestIndexWriterMergePolicy.testMergeOnCommit(TestIndexWriterMergePolicy.java:400)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Mar 05, 2020 6:15:42 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@b68981), locale=fr, timezone=Mexico/BajaSur
[junit4] 2> NOTE: Linux 5.3.0-40-generic i386/Oracle Corporation 1.8.0_201 (32-bit)/cpus=16,threads=1,free=15881728,total=76828672
[junit4] 2> NOTE: All tests run in this JVM: [TestDocument, TestSoftDeletesRetentionMergePolicy, TestIndexTooManyDocs, TestFloatRangeFieldQueries, TestFieldType, TestPagedBytes, Test2BPostingsBytes, TestPointValues, TestIntroSorter, TestIndexWriterMergePolicy]
[junit4] Completed [231/563 (1!)] on J4 in 51.56s, 8 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 63289 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2359/consoleText
[repro] Revision: 1573d3d2d83c5008dd179736feb7218906830fb0
[repro] Ant options: "-Dargs=-client -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestIndexWriterMergePolicy -Dtests.method=testMergeOnCommit -Dtests.seed=2375C8C036BF4B21 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr -Dtests.timezone=Mexico/BajaSur -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] lucene/core
[repro] TestIndexWriterMergePolicy
[repro] ant compile-test
[...truncated 158 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestIndexWriterMergePolicy" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseSerialGC" -Dtests.seed=2375C8C036BF4B21 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr -Dtests.timezone=Mexico/BajaSur -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 46 lines...]
[junit4] Suite: org.apache.lucene.index.TestIndexWriterMergePolicy
[junit4] OK 0.33s J3 | TestIndexWriterMergePolicy.testNormalCase
[junit4] IGNOR/A 0.00s J3 | TestIndexWriterMergePolicy.testMaxBufferedDocsChange
[junit4] > Assumption #1: 'nightly' test group is disabled (@Nightly())
[junit4] OK 0.53s J3 | TestIndexWriterMergePolicy.testForceFlush
[junit4] OK 0.48s J3 | TestIndexWriterMergePolicy.testMergeDocCount0
[junit4] OK 0.00s J3 | TestIndexWriterMergePolicy.testSetters
[junit4] OK 0.06s J3 | TestIndexWriterMergePolicy.testNoOverMerge
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexWriterMergePolicy -Dtests.method=testMergeOnCommit -Dtests.seed=2375C8C036BF4B21 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=fr -Dtests.timezone=Mexico/BajaSur -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 35.2s J3 | TestIndexWriterMergePolicy.testMergeOnCommit <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<6>
[junit4] > at __randomizedtesting.SeedInfo.seed([2375C8C036BF4B21:7F3D2C7D75AA901]:0)
[junit4] > at org.apache.lucene.index.TestIndexWriterMergePolicy.testMergeOnCommit(TestIndexWriterMergePolicy.java:400)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] OK 0.30s J3 | TestIndexWriterMergePolicy.testMergeFactorChange
[junit4] 2> Mar 05, 2020 7:05:11 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@765a66), locale=fr, timezone=Mexico/BajaSur
[junit4] 2> NOTE: Linux 5.3.0-40-generic i386/Oracle Corporation 1.8.0_201 (32-bit)/cpus=16,threads=1,free=33001216,total=81321984
[junit4] 2> NOTE: All tests run in this JVM: [TestIndexWriterMergePolicy]
[junit4] Completed [2/5 (1!)] on J3 in 40.64s, 8 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 49 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1594: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1121: There were test failures: 5 suites, 40 tests, 1 failure, 5 ignored (5 assumptions) [seed: 2375C8C036BF4B21]
Total time: 56 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 1/5 failed: org.apache.lucene.index.TestIndexWriterMergePolicy
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
mv: cannot stat 'solr/build': No such file or directory
+ true
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=1573d3d2d83c5008dd179736feb7218906830fb0, workspace=/home/jenkins/workspace/Lucene-Solr-8.x-Linux
[WARNINGS] Computing warning deltas based on reference build #2358
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
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
[JENKINS] Lucene-Solr-8.x-Linux (32bit/jdk1.8.0_201) - Build # 2358
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2358/
Java: 32bit/jdk1.8.0_201 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.MathExpressionTest.testZipFDistribution
Error Message:
Zipf distribution not descending!!!
Stack Trace:
java.lang.Exception: Zipf distribution not descending!!!
at __randomizedtesting.SeedInfo.seed([87E0851FF5391C4D:A355E83BE2911465]:0)
at org.apache.solr.client.solrj.io.stream.MathExpressionTest.testZipFDistribution(MathExpressionTest.java:3766)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16964 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[junit4] 2> 1874 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1883 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1890 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/data-dir-1-001
[junit4] 2> 1982 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2126 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.e.j.u.log Logging initialized @2116ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2131 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2157 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001
[junit4] 2> 2159 WARN (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer TEST_HOME() does not exist - solrj test?
[junit4] 2> 2165 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2167 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2167 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2267 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:45387
[junit4] 2> 2267 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45387
[junit4] 2> 2270 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45387
[junit4] 2> 2270 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45387
[junit4] 2> 2341 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2366 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2366 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2384 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2402 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2402 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2404 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2413 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2413 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2800 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2800 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2802 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2804 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2804 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2804 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2804 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2804 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2804 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2804 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2804 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2804 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2808 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 2808 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 2808 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 2813 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 2852 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2852 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2853 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2853 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2856 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2856 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2859 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2859 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2859 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2878 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@bfe91c{/solr,null,AVAILABLE}
[junit4] 2> 2878 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@110b7a2{/solr,null,AVAILABLE}
[junit4] 2> 2885 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2885 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2885 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c7b2fe{/solr,null,AVAILABLE}
[junit4] 2> 2886 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2889 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d64ce0{/solr,null,AVAILABLE}
[junit4] 2> 2897 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b0dcb9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42717}
[junit4] 2> 2897 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.Server Started @2928ms
[junit4] 2> 2897 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42717}
[junit4] 2> 2905 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@126c442{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42711}
[junit4] 2> 2905 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.Server Started @2936ms
[junit4] 2> 2905 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42711}
[junit4] 2> 2996 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@fbba59{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43037}
[junit4] 2> 2996 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server Started @3027ms
[junit4] 2> 2996 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43037}
[junit4] 2> 3001 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c9960b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43947}
[junit4] 2> 3001 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server Started @3032ms
[junit4] 2> 3001 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43947}
[junit4] 2> 3069 ERROR (jetty-launcher-8-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3069 ERROR (jetty-launcher-8-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3069 ERROR (jetty-launcher-8-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3069 ERROR (jetty-launcher-8-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 3070 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T04:51:38.718Z
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T04:51:38.718Z
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T04:51:38.718Z
[junit4] 2> 3074 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-05T04:51:38.718Z
[junit4] 2> 3096 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3096 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3096 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3101 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 3113 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3116 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3117 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3124 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3132 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3132 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3133 INFO (zkConnectionManagerCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3133 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3135 INFO (zkConnectionManagerCallback-13-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3135 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3137 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3140 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3140 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3141 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3145 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3145 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 5637 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5973 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@114163[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5973 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@114163[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6131 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1324224[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6131 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1324224[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6142 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45387/solr
[junit4] 2> 6206 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6221 INFO (zkConnectionManagerCallback-26-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6221 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6357 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6374 INFO (zkConnectionManagerCallback-28-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6374 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6421 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 6423 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1bf17a9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6423 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1bf17a9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6465 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@c8213e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6465 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@c8213e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6467 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45387/solr
[junit4] 2> 6468 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6541 INFO (zkConnectionManagerCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6541 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6669 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6714 INFO (zkConnectionManagerCallback-38-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6715 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6723 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42711_solr
[junit4] 2> 6732 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.Overseer Overseer (id=72123477787672584-127.0.0.1:42711_solr-n_0000000000) starting
[junit4] 2> 6735 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42717_solr
[junit4] 2> 6777 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6782 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6889 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 6920 WARN (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 6985 INFO (OverseerStateUpdate-72123477787672584-127.0.0.1:42711_solr-n_0000000000) [n:127.0.0.1:42711_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42711_solr
[junit4] 2> 7019 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42711_solr as DOWN
[junit4] 2> 7076 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 7076 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42711_solr
[junit4] 2> 7090 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 7092 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 7110 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 7114 WARN (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 7233 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7234 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7237 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 7241 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@15f02f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 7241 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@15f02f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 7337 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@578e7e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 7337 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@578e7e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 7339 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45387/solr
[junit4] 2> 7357 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 7386 INFO (zkConnectionManagerCallback-50-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7386 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 7567 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42711.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 7567 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 7597 INFO (zkConnectionManagerCallback-52-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7598 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 7599 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42711.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 7599 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42711.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 7617 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42717.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 7622 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42717.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 7622 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42717.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 7641 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 7661 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:43037_solr as DOWN
[junit4] 2> 7661 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:42717_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node3/.
[junit4] 2> 7663 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:42711_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node4/.
[junit4] 2> 7663 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 7663 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43037_solr
[junit4] 2> 7685 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 7685 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 7690 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 7692 INFO (zkCallback-51-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 7702 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 7775 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7915 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43037.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 8024 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43037.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 8025 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43037.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 8026 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:43037_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node1/.
[junit4] 2> 9863 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 9864 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@15da1b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 9864 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@15da1b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 9869 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@418793[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 9869 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@418793[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 9870 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45387/solr
[junit4] 2> 9877 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 9883 INFO (zkConnectionManagerCallback-62-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 9883 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 9988 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 9989 INFO (zkConnectionManagerCallback-64-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 9989 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 9995 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 9999 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:43947_solr as DOWN
[junit4] 2> 10001 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 10001 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43947_solr
[junit4] 2> 10002 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 10002 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 10002 INFO (zkCallback-51-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 10002 INFO (zkCallback-63-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 10007 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 10008 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 10028 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 10053 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43947.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 10080 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43947.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 10081 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43947.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 10082 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:43947_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node2/.
[junit4] 2> 10173 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 10175 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 10177 INFO (zkConnectionManagerCallback-73-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 10177 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 10178 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 10179 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45387/solr ready
[junit4] 2> 10358 INFO (qtp19630671-194) [ ] o.e.j.u.TypeUtil JVM Runtime does not support Modules
[junit4] 2> 10486 INFO (qtp19630671-194) [n:127.0.0.1:42717_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 10504 INFO (OverseerThreadFactory-18-thread-1-processing-n:127.0.0.1:42711_solr) [n:127.0.0.1:42711_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1_collection
[junit4] 2> 10638 INFO (OverseerStateUpdate-72123477787672584-127.0.0.1:42711_solr-n_0000000000) [n:127.0.0.1:42711_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42711/solr",
[junit4] 2> "node_name":"127.0.0.1:42711_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 10643 INFO (OverseerStateUpdate-72123477787672584-127.0.0.1:42711_solr-n_0000000000) [n:127.0.0.1:42711_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_collection_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:43947/solr",
[junit4] 2> "node_name":"127.0.0.1:43947_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 10866 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr x:collection1_collection_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_collection_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 10866 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr x:collection1_collection_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_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 10945 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 10946 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 11069 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 11069 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.IndexSchema [collection1_collection_shard2_replica_n3] Schema name=test
[junit4] 2> 11141 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_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> 11141 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11149 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11149 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_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> 11152 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11152 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_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> 11157 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_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> 11157 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11471 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_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> 11471 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11755 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11755 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11760 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11760 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 11776 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 11776 WARN (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 11776 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 11776 WARN (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 11778 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 11778 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 11786 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard2_replica_n3' using configuration from configset conf, trusted=true
[junit4] 2> 11786 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_collection_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 11805 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43947.solr.core.collection1_collection.shard2.replica_n3' (registry 'solr.core.collection1_collection.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 11805 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42711.solr.core.collection1_collection.shard1.replica_n1' (registry 'solr.core.collection1_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10184dc
[junit4] 2> 11819 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrCore [[collection1_collection_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node2/collection1_collection_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node2/./collection1_collection_shard2_replica_n3/data/]
[junit4] 2> 11819 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node4/collection1_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001/tempDir-001/node4/./collection1_collection_shard1_replica_n1/data/]
[junit4] 2> 12712 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 12712 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 12712 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 12712 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 12723 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 12723 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 12723 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 12723 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 12744 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f4[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 12744 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e67c9[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 12756 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 12758 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 12772 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 12772 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 12791 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 12791 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 12831 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1660298365213081600
[junit4] 2> 12831 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1660298365213081600
[junit4] 2> 12842 INFO (searcherExecutor-42-thread-1-processing-n:127.0.0.1:43947_solr x:collection1_collection_shard2_replica_n3 c:collection1_collection s:shard2 r:core_node4) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@2e67c9[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 12842 INFO (searcherExecutor-41-thread-1-processing-n:127.0.0.1:42711_solr x:collection1_collection_shard1_replica_n1 c:collection1_collection s:shard1 r:core_node2) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@3f4[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 12855 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 12855 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 12862 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1_collection/leaders/shard1
[junit4] 2> 12862 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1_collection/leaders/shard2
[junit4] 2> 12873 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 12873 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 12873 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 12873 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 12874 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/
[junit4] 2> 12874 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/
[junit4] 2> 12875 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 12875 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 12876 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SyncStrategy http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/ has no replicas
[junit4] 2> 12876 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 12878 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1_collection/leaders/shard2/leader after winning as /collections/collection1_collection/leader_elect/shard2/election/72123477787672590-core_node4-n_0000000000
[junit4] 2> 12878 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1_collection/leaders/shard1/leader after winning as /collections/collection1_collection/leader_elect/shard1/election/72123477787672584-core_node2-n_0000000000
[junit4] 2> 12885 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/ shard1
[junit4] 2> 12886 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/ shard2
[junit4] 2> 12991 INFO (zkCallback-63-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 12991 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 12995 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 12995 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 13003 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_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_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2144
[junit4] 2> 13003 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_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_collection_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2144
[junit4] 2> 13045 INFO (qtp19630671-194) [n:127.0.0.1:42717_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> 13100 INFO (zkCallback-63-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 13100 INFO (zkCallback-63-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 13100 INFO (zkCallback-27-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 13101 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 13101 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_collection/state.json] for collection [collection1_collection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 13113 INFO (qtp19630671-194) [n:127.0.0.1:42717_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2665
[junit4] 2> 13122 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1_collection failOnTimeout:true timeout (sec):45
[junit4] 2> 13124 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1_collection
[junit4] 2> 13126 INFO (qtp19630671-194) [n:127.0.0.1:42717_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with params collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 13153 INFO (OverseerCollectionConfigSetProcessor-72123477787672584-127.0.0.1:42711_solr-n_0000000000) [n:127.0.0.1:42711_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> 13262 INFO (qtp19630671-194) [n:127.0.0.1:42717_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collections=collection1_collection&name=collection1&action=CREATEALIAS&wt=javabin&version=2} status=0 QTime=135
[junit4] 2> 13297 INFO (TEST-MathExpressionTest.testPow-seed#[87E0851FF5391C4D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPow
[junit4] 2> 13519 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 13519 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 13536 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1660298365952327680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 13536 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 13537 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 13537 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_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:43947/solr/collection1_collection_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=1
[junit4] 2> 13538 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1660298365954424832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 13538 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 13538 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 13538 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_collection/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 13539 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2} status=0 QTime=232
[junit4] 2> 13544 INFO (qtp19630671-194) [n:127.0.0.1:42717_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 13558 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,+a%3Darray(10,+20,+30),+b%3Dpow(a,+2),+c%3Dpow(2,+a),+d%3Dpow(10,+3),+e%3Dpow(a,+array(1,+2,+3)))&_forwardedCount=1&wt=json&version=2.2} status=0 QTime=11
[junit4] 2> 13569 INFO (TEST-MathExpressionTest.testPow-seed#[87E0851FF5391C4D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPow
[junit4] 2> 13599 INFO (TEST-MathExpressionTest.testLength-seed#[87E0851FF5391C4D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLength
[junit4] 2> 13607 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 13610 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1660298366029922304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 13610 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 13610 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 13610 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_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:42711/solr/collection1_collection_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=1
[junit4] 2> 13611 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1660298366030970880,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 13612 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 13612 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 13612 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 13707 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2} status=0 QTime=90
[junit4] 2> 13709 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&wt=javabin&version=2} status=0 QTime=92
[junit4] 2> 13715 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1660298366140022784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 13715 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3508a5 commitCommandVersion:1660298366140022784
[junit4] 2> 13985 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@11b3b8c[collection1_collection_shard1_replica_n1] main]
[junit4] 2> 13986 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 13991 INFO (searcherExecutor-41-thread-1-processing-n:127.0.0.1:42711_solr x:collection1_collection_shard1_replica_n1 c:collection1_collection s:shard1 r:core_node2) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_collection_shard1_replica_n1] Registered new searcher Searcher@11b3b8c[collection1_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.6.0):C8:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.6.0, os.arch=i386, java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.3.0-40-generic, timestamp=1583383909504}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]))}
[junit4] 2> 13991 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_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:43947/solr/collection1_collection_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=276
[junit4] 2> 13993 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1660298366431526912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 13993 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1a96613 commitCommandVersion:1660298366431526912
[junit4] 2> 13997 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@156e2df[collection1_collection_shard2_replica_n3] main]
[junit4] 2> 13998 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 14001 INFO (searcherExecutor-42-thread-1-processing-n:127.0.0.1:43947_solr x:collection1_collection_shard2_replica_n3 c:collection1_collection s:shard2 r:core_node4) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.SolrCore [collection1_collection_shard2_replica_n3] Registered new searcher Searcher@156e2df[collection1_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.6.0):C6:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.6.0, os.arch=i386, java.runtime.version=1.8.0_201-b09, source=flush, os.version=5.3.0-40-generic, timestamp=1583383909640}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]))}
[junit4] 2> 14002 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1_collection:4&commit=true&wt=javabin&version=2} status=0 QTime=292
[junit4] 2> 14012 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/stream params={indent=off&expr=let(a%3Dsearch(collection1,+q%3D"col_s:a",+fl%3D"price_f,+order_i",+sort%3D"order_i+asc"),+b%3Dsearch(collection1,+q%3D"col_s:b",+fl%3D"price_f,+order_i",+sort%3D"order_i+asc"),+c%3Dcol(a,+price_f),+d%3Dcol(b,+price_f),+e%3Dregress(c,+d),+tuple(regress%3De,+p%3Dpredict(e,+300),+l%3Dlength(d)))&_forwardedCount=1&wt=json&version=2.2} status=0 QTime=5
[junit4] 2> 14014 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 14016 INFO (zkConnectionManagerCallback-80-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 14016 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 14017 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 14018 INFO (qtp33086275-200) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45387/solr ready
[junit4] 2> 14087 INFO (qtp33086275-241) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/&rows=10&version=2&q=col_s:a&omitHeader=false&NOW=1583383909666&isShard=true&wt=javabin} hits=5 status=0 QTime=44
[junit4] 2> 14087 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/&rows=10&version=2&q=col_s:a&omitHeader=false&NOW=1583383909666&isShard=true&wt=javabin} hits=2 status=0 QTime=44
[junit4] 2> 14123 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=price_f,+order_i&fl=id&shards.purpose=64&shard.url=http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/&version=2&q=col_s:a&omitHeader=false&NOW=1583383909666&ids=1,4&isShard=true&wt=javabin} status=0 QTime=3
[junit4] 2> 14123 INFO (qtp33086275-241) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=price_f,+order_i&fl=id&shards.purpose=64&shard.url=http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/&version=2&q=col_s:a&omitHeader=false&NOW=1583383909666&ids=2,3,5,6,7&isShard=true&wt=javabin} status=0 QTime=4
[junit4] 2> 14127 INFO (qtp33086275-234) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=col_s:a&_stateVer_=collection1_collection:4&fl=price_f,+order_i&sort=order_i+asc&wt=javabin&version=2} hits=7 status=0 QTime=104
[junit4] 2> 14131 INFO (qtp33086275-234) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/&rows=10&version=2&q=col_s:b&omitHeader=false&NOW=1583383909773&isShard=true&wt=javabin} hits=1 status=0 QTime=0
[junit4] 2> 14131 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/&rows=10&version=2&q=col_s:b&omitHeader=false&NOW=1583383909773&isShard=true&wt=javabin} hits=6 status=0 QTime=0
[junit4] 2> 14142 INFO (qtp33086275-234) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=price_f,+order_i&fl=id&shards.purpose=64&shard.url=http://127.0.0.1:43947/solr/collection1_collection_shard2_replica_n3/&version=2&q=col_s:b&omitHeader=false&NOW=1583383909773&ids=9&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 14142 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_collection_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1_collection:4&fl=price_f,+order_i&fl=id&shards.purpose=64&shard.url=http://127.0.0.1:42711/solr/collection1_collection_shard1_replica_n1/&version=2&q=col_s:b&omitHeader=false&NOW=1583383909773&ids=11,12,13,14,8,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 14144 INFO (qtp33086275-241) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/select params={q=col_s:b&_stateVer_=collection1_collection:4&fl=price_f,+order_i&sort=order_i+asc&wt=javabin&version=2} hits=7 status=0 QTime=15
[junit4] 2> 14187 INFO (TEST-MathExpressionTest.testLength-seed#[87E0851FF5391C4D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLength
[junit4] 2> 14226 INFO (TEST-MathExpressionTest.testRecip-seed#[87E0851FF5391C4D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRecip
[junit4] 2> 14272 INFO (qtp33086275-234) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@12e3cc3[collection1_collection_shard2_replica_n3] realtime]
[junit4] 2> 14272 INFO (qtp24751706-201) [n:127.0.0.1:42711_solr c:collection1_collection s:shard1 r:core_node2 x:collection1_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@75a046[collection1_collection_shard1_replica_n1] realtime]
[junit4] 2> 14273 INFO (qtp33086275-234) [n:127.0.0.1:43947_solr c:collection1_collection s:shard2 r:core_node4 x:collection1_collection_shard2_replica_n3 ] o.a.s.c.S.Request [collection1_collection_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=
[...truncated too long message...]
2> 24663 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@110b7a2{/solr,null,UNAVAILABLE}
[junit4] 2> 24663 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 24668 INFO (jetty-closer-88-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 24668 INFO (jetty-closer-88-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@fe6cb8: rootName = solr_42711, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@6e2122
[junit4] 2> 24672 INFO (jetty-closer-88-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 24672 INFO (jetty-closer-88-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@18f1166: rootName = solr_42711, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@17c51f2
[junit4] 2> 24672 INFO (jetty-closer-88-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 24678 INFO (closeThreadPool-103-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72123477787672584-127.0.0.1:42711_solr-n_0000000000) closing
[junit4] 2> 24679 INFO (OverseerStateUpdate-72123477787672584-127.0.0.1:42711_solr-n_0000000000) [n:127.0.0.1:42711_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42711_solr
[junit4] 2> 24679 INFO (jetty-closer-88-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 24679 INFO (jetty-closer-88-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@193a39e: rootName = solr_43947, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@6e2122
[junit4] 2> 24681 INFO (OverseerAutoScalingTriggerThread-72123477787672584-127.0.0.1:42711_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 24681 INFO (closeThreadPool-103-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72123477787672584-127.0.0.1:42711_solr-n_0000000000) closing
[junit4] 2> 24686 INFO (jetty-closer-88-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 24686 INFO (jetty-closer-88-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@130f92c: rootName = solr_43947, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@17c51f2
[junit4] 2> 24686 INFO (jetty-closer-88-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 24687 INFO (zkCallback-63-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 24759 INFO (jetty-closer-88-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@fbba59{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 24759 INFO (jetty-closer-88-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@d64ce0{/solr,null,UNAVAILABLE}
[junit4] 2> 24759 INFO (jetty-closer-88-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 24858 INFO (jetty-closer-88-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72123477787672584-127.0.0.1:42711_solr-n_0000000000) closing
[junit4] 2> 24859 INFO (jetty-closer-88-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@126c442{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 24860 INFO (jetty-closer-88-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@bfe91c{/solr,null,UNAVAILABLE}
[junit4] 2> 24860 INFO (jetty-closer-88-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 24960 INFO (jetty-closer-88-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@c9960b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 24960 INFO (jetty-closer-88-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c7b2fe{/solr,null,UNAVAILABLE}
[junit4] 2> 24960 INFO (jetty-closer-88-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 24960 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 25164 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/collection1_collection/terms/shard1
[junit4] 2> 3 /solr/collections/collection1_collection/terms/shard2
[junit4] 2> 2 /solr/collections/collection1_collection/collectionprops.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/collections/collection1_collection/state.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 2 /solr/overseer_elect/election/72123477787672584-127.0.0.1:42711_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 22 /solr/live_nodes
[junit4] 2> 21 /solr/overseer/queue
[junit4] 2> 11 /solr/collections
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 25167 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45387
[junit4] 2> 25167 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45387
[junit4] 2> 25167 INFO (SUITE-MathExpressionTest-seed#[87E0851FF5391C4D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45387
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_87E0851FF5391C4D-001
[junit4] 2> Mar 05, 2020 4:52:00 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {multiDefault=PostingsFormat(name=LuceneFixedGap), price_f=PostingsFormat(name=LuceneFixedGap), intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), test_dt=BlockTreeOrds(blocksize=128), price_i=Lucene84, test_t=Lucene84, col_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), order_i=PostingsFormat(name=LuceneFixedGap), loc_p_1_d1=BlockTreeOrds(blocksize=128), _version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), loc_p_0_d1=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=Lucene84, text=BlockTreeOrds(blocksize=128), miles_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), timestamp=PostingsFormat(name=LuceneFixedGap)}, docValues:{multiDefault=DocValuesFormat(name=Lucene80), price_f=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Direct), test_dt=DocValuesFormat(name=Asserting), price_i=DocValuesFormat(name=Lucene80), col_s=DocValuesFormat(name=Direct), order_i=DocValuesFormat(name=Lucene80), loc_p_1_d1=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Direct), loc_p_0_d1=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene80), miles_i=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=316, maxMBSortInHeap=6.810753596645633, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4a27b2), locale=sr-RS, timezone=Pacific/Pitcairn
[junit4] 2> NOTE: Linux 5.3.0-40-generic i386/Oracle Corporation 1.8.0_201 (32-bit)/cpus=16,threads=2,free=68181864,total=185597952
[junit4] 2> NOTE: All tests run in this JVM: [MathExpressionTest]
[junit4] Completed [17/208 (1!)] on J3 in 25.45s, 140 tests, 1 error <<< FAILURES!
[...truncated 47442 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2358/consoleText
[repro] Revision: 1573d3d2d83c5008dd179736feb7218906830fb0
[repro] Ant options: "-Dargs=-client -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=MathExpressionTest -Dtests.method=testZipFDistribution -Dtests.seed=87E0851FF5391C4D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-RS -Dtests.timezone=Pacific/Pitcairn -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/solrj
[repro] MathExpressionTest
[repro] ant compile-test
[...truncated 2554 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.MathExpressionTest" -Dtests.showOutput=onerror "-Dargs=-client -XX:+UseG1GC" -Dtests.seed=87E0851FF5391C4D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-RS -Dtests.timezone=Pacific/Pitcairn -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 771 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[repro] Exiting with code 0
[...truncated 43 lines...]