You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/04/08 07:32:36 UTC
[JENKINS-EA] Lucene-Solr-BadApples-8.x-Linux
(64bit/jdk-13-ea+shipilev-fastdebug) - Build # 41 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/41/
Java: 64bit/jdk-13-ea+shipilev-fastdebug -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi
Error Message:
expected:<2> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<2> but was:<3>
at __randomizedtesting.SeedInfo.seed([68C4D44EEE29746D:3FED2FFB35DB9676]: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.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:822)
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:567)
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:835)
FAILED: org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic
Error Message:
{} expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: {} expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([68C4D44EEE29746D:C33EC95B31F5F243]: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.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic(SolrRrdBackendFactoryTest.java:92)
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:567)
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:835)
Build Log:
[...truncated 13882 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> 1915183 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/init-core-data-001
[junit4] 2> 1915197 WARN (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
[junit4] 2> 1915197 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1915198 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1915199 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001
[junit4] 2> 1915199 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1915213 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1915213 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1915313 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:45137
[junit4] 2> 1915313 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45137
[junit4] 2> 1915313 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45137
[junit4] 2> 1915408 INFO (zkConnectionManagerCallback-6415-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1915445 INFO (zkConnectionManagerCallback-6417-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1915469 INFO (zkConnectionManagerCallback-6419-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1915485 WARN (jetty-launcher-6420-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1915485 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1915485 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1915486 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b769-20190316-jdk-1312
[junit4] 2> 1915489 WARN (jetty-launcher-6420-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1915489 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1915490 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1915490 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 13-testing+0-builds.shipilev.net-openjdk-jdk-b769-20190316-jdk-1312
[junit4] 2> 1915593 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1915593 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1915593 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1915613 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@682a80c6{/solr,null,AVAILABLE}
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1dfd9d88{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40189}
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.e.j.s.Server Started @1915675ms
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40189}
[junit4] 2> 1915615 ERROR (jetty-launcher-6420-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1915615 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-08T06:12:12.013898Z
[junit4] 2> 1915645 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1915645 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1915645 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1915653 INFO (zkConnectionManagerCallback-6422-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1915653 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1915665 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@701e348e{/solr,null,AVAILABLE}
[junit4] 2> 1915666 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8b008ea{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46033}
[junit4] 2> 1915666 INFO (jetty-launcher-6420-thread-1) [ ] o.e.j.s.Server Started @1915727ms
[junit4] 2> 1915666 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46033}
[junit4] 2> 1915667 ERROR (jetty-launcher-6420-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1915667 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1915667 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.1.0
[junit4] 2> 1915667 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1915667 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1915667 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-08T06:12:12.065222Z
[junit4] 2> 1915701 INFO (zkConnectionManagerCallback-6425-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1915702 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1915914 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1915928 WARN (jetty-launcher-6420-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7d6919c3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1915954 WARN (jetty-launcher-6420-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1635a7f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1915955 INFO (jetty-launcher-6420-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45137/solr
[junit4] 2> 1915958 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1915969 WARN (jetty-launcher-6420-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@505866c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1915977 INFO (zkConnectionManagerCallback-6432-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916018 WARN (jetty-launcher-6420-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@32d9334e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1916019 INFO (jetty-launcher-6420-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45137/solr
[junit4] 2> 1916029 INFO (zkConnectionManagerCallback-6436-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916077 INFO (zkConnectionManagerCallback-6440-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916132 INFO (zkConnectionManagerCallback-6444-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916139 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46033_solr
[junit4] 2> 1916140 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.Overseer Overseer (id=72337990408798216-127.0.0.1:46033_solr-n_0000000000) starting
[junit4] 2> 1916144 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40189_solr
[junit4] 2> 1916177 INFO (zkCallback-6443-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1916195 INFO (zkCallback-6435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1916260 INFO (zkConnectionManagerCallback-6451-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916261 INFO (zkConnectionManagerCallback-6456-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916263 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1916263 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1916263 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45137/solr ready
[junit4] 2> 1916264 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45137/solr ready
[junit4] 2> 1916285 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46033_solr
[junit4] 2> 1916296 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1916313 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46033_solr as DOWN
[junit4] 2> 1916314 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1916314 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46033_solr
[junit4] 2> 1916315 INFO (zkCallback-6435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1916315 INFO (zkCallback-6443-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1916329 INFO (zkCallback-6455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1916340 INFO (zkCallback-6450-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1916344 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40189.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1916371 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40189.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1916371 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40189.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1916373 INFO (jetty-launcher-6420-thread-2) [n:127.0.0.1:40189_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node2/.
[junit4] 2> 1916376 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1916486 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46033.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1916509 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46033.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1916509 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46033.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1916511 INFO (jetty-launcher-6420-thread-1) [n:127.0.0.1:46033_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node1/.
[junit4] 2> 1916661 INFO (zkConnectionManagerCallback-6463-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1916662 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1916663 INFO (SUITE-AutoScalingHandlerTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45137/solr ready
[junit4] 2> 1916783 INFO (TEST-AutoScalingHandlerTest.testErrorHandling-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testErrorHandling
[junit4] 2> 1916798 ERROR (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY}, {shard=#EACH, node=#ANY}, {nodeRole=overseer, replica=0}], errorMessages=[replica is required in {
[junit4] 2> "shard":"#EACH",
[junit4] 2> "node":"#ANY"}]}],
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:210)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:784)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:750)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:397)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:343)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:165)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2>
[junit4] 2> 1916798 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=400 QTime=2
[junit4] 2> 1916800 INFO (TEST-AutoScalingHandlerTest.testErrorHandling-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testErrorHandling
[junit4] 2> 1916837 INFO (TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
[junit4] 2> 1917004 ERROR (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload, errors: [{set-policy={xyz=[{replica=<2, shard=#EACH, node=#ANY}, {nodeRole=!overseer, replica=0}], policy1=[{cores=<2, node=#ANY}, {replica=<2, shard=#EACH, node=#ANY}]}, errorMessages=[cores is only allowed in 'cluster-policy']}],
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:210)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:336)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:750)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:397)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:343)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:165)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2>
[junit4] 2> 1917004 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=400 QTime=156
[junit4] 2> 1917043 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1917045 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917046 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917048 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1917050 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917052 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917053 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917054 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917056 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917057 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1917095 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=90
[junit4] 2> 1917108 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1917114 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917116 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917119 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1917120 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917121 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917123 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917124 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917125 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917126 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917129 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=32
[junit4] 2> 1917186 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917188 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917190 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1917192 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917195 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917196 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917198 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917199 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917200 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917201 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1917202 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=69
[junit4] 2> 1918466 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=43
[junit4] 2> 1918468 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918469 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918470 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918472 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918473 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918474 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918475 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918476 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918477 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918486 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=1282
[junit4] 2> 1918489 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918490 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918491 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918491 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918492 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918493 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918494 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918495 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918495 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918496 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm} status=0 QTime=0
[junit4] 2> 1918497 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 1918500 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918524 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=11
[junit4] 2> 1918526 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918527 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918528 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918529 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918530 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918531 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918532 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918533 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName,os.systemLoadAverage&wt=javabin&version=2&group=solr.jvm,solr.core} status=0 QTime=0
[junit4] 2> 1918536 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=38
[junit4] 2> 1918537 INFO (TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
[junit4] 2> 1918764 INFO (TEST-AutoScalingHandlerTest.testReadApi-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 1918768 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918769 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918770 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918771 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918772 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918774 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918775 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918776 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918777 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918778 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918779 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 1918789 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918790 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918791 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918791 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918792 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918794 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918794 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918795 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918796 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918797 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918800 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 1918879 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=76
[junit4] 2> 1918887 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=4
[junit4] 2> 1918892 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=4
[junit4] 2> 1918894 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=2
[junit4] 2> 1918898 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918900 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918902 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918903 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918904 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918906 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918908 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=106
[junit4] 2> 1918910 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918911 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918914 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918915 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918917 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918919 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918920 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918921 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918922 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918923 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918925 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 1918926 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1918929 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918930 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918931 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918932 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918934 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918935 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918938 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918939 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1918941 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1918942 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919024 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling/diagnostics params={wt=javabin&version=2} status=0 QTime=96
[junit4] 2> 1919222 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919224 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919230 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919232 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919235 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919237 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919239 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919241 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919242 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919244 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919246 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=27
[junit4] 2> 1919251 INFO (qtp820571330-17030) [n:127.0.0.1:40189_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=3&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1919281 INFO (OverseerThreadFactory-5721-thread-1-processing-n:127.0.0.1:46033_solr) [n:127.0.0.1:46033_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection readApiTestViolations
[junit4] 2> 1919384 WARN (OverseerThreadFactory-5721-thread-1-processing-n:127.0.0.1:46033_solr) [n:127.0.0.1:46033_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 6 on collection readApiTestViolations is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1919387 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919389 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919391 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919393 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1919394 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919396 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919397 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919398 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919400 INFO (qtp1586264742-16972) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919401 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1919441 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40189/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1919444 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46033/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1919446 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n5",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40189/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1919450 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46033/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1919455 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n9",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:40189/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1919458 INFO (OverseerStateUpdate-72337990408798216-127.0.0.1:46033_solr-n_0000000000) [n:127.0.0.1:46033_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n11",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46033/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1919672 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr x:readApiTestViolations_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1919672 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr x:readApiTestViolations_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1919674 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr x:readApiTestViolations_shard1_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1919679 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr x:readApiTestViolations_shard1_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1919692 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr x:readApiTestViolations_shard1_replica_n7] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n7&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1919712 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr x:readApiTestViolations_shard1_replica_n9] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n9&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1919713 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr x:readApiTestViolations_shard1_replica_n11] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n11&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1920694 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1920699 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1920706 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1920709 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n3] Schema name=minimal
[junit4] 2> 1920709 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1920709 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1920711 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1920711 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n3' using configuration from collection readApiTestViolations, trusted=true
[junit4] 2> 1920712 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46033.solr.core.readApiTestViolations.shard1.replica_n3' (registry 'solr.core.readApiTestViolations.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1920712 INFO (qtp1586264742-16973) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n3/data/]
[junit4] 2> 1920712 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1920712 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n1' using configuration from collection readApiTestViolations, trusted=true
[junit4] 2> 1920713 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40189.solr.core.readApiTestViolations.shard1.replica_n1' (registry 'solr.core.readApiTestViolations.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1920713 INFO (qtp820571330-16964) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n1/data/]
[junit4] 2> 1920722 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1920727 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0
[junit4] 2> 1920734 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n11] Schema name=minimal
[junit4] 2> 1920738 INFO (qtp820571330-16967) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node10 x:readApiTestViolations_shard1_replica_n9] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n9] Schema name=minimal
[junit4] 2> 1920743 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n5] Schema name=minimal
[junit4] 2> 1920819 INFO (qtp1586264742-16969) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node8 x:readApiTestViolations_shard1_replica_n7] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n7] Schema name=minimal
[junit4] 2> 1920820 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1920820 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n11' using configuration from collection readApiTestViolations, trusted=true
[junit4] 2> 1920872 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46033.solr.core.readApiTestViolations.shard1.replica_n11' (registry 'solr.core.readApiTestViolations.shard1.replica_n11') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1920872 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1920872 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n5' using configuration from collection readApiTestViolations, trusted=true
[junit4] 2> 1920872 INFO (qtp1586264742-17043) [n:127.0.0.1:46033_solr c:readApiTestViolations s:shard1 r:core_node12 x:readApiTestViolations_shard1_replica_n11] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n11] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n11], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n11/data/]
[junit4] 2> 1920873 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40189.solr.core.readApiTestViolations.shard1.replica_n5' (registry 'solr.core.readApiTestViolations.shard1.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@511c46bf
[junit4] 2> 1920873 INFO (qtp820571330-16966) [n:127.0.0.1:40189_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n5] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE29746D-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n5], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_68C4D44EEE2974
[...truncated too long message...]
ples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.metrics.rrd.SolrRrdBackendFactoryTest_68C4D44EEE29746D-001/init-core-data-001
[junit4] 2> 3536482 WARN (SUITE-SolrRrdBackendFactoryTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
[junit4] 2> 3536482 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 3536483 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[68C4D44EEE29746D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 3536486 INFO (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasic
[junit4] 2> 3536748 INFO (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[68C4D44EEE29746D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasic
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrRrdBackendFactoryTest -Dtests.method=testBasic -Dtests.seed=68C4D44EEE29746D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=rw -Dtests.timezone=America/St_Vincent -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.33s J1 | SolrRrdBackendFactoryTest.testBasic <<<
[junit4] > Throwable #1: java.lang.AssertionError: {} expected:<1> but was:<0>
[junit4] > at __randomizedtesting.SeedInfo.seed([68C4D44EEE29746D:C33EC95B31F5F243]:0)
[junit4] > at org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic(SolrRrdBackendFactoryTest.java:92)
[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:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.metrics.rrd.SolrRrdBackendFactoryTest_68C4D44EEE29746D-001
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=3, maxDocsPerChunk=267, blockSize=384), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=3, blockSize=384)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@64ecd5c0), locale=rw, timezone=America/St_Vincent
[junit4] 2> NOTE: Linux 4.18.0-15-generic amd64/Aleksey Shipilev 13-testing (64-bit)/cpus=8,threads=1,free=115972160,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [URLClassifyProcessorTest, TestStressLiveNodes, TestFieldTypeResource, DOMUtilTest, HdfsBasicDistributedZk2Test, TestSolrCoreParser, TestInitQParser, BlockJoinFacetRandomTest, CachingDirectoryFactoryTest, SolrIndexConfigTest, TestExtendedDismaxParser, TestInfoStreamLogging, TestRTimerTree, LeaderElectionContextKeyTest, TestGraphTermsQParserPlugin, TransactionLogTest, TestSimGenericDistributedQueue, TestPhraseSuggestions, SolrCoreCheckLockOnStartupTest, TestRawTransformer, JavabinLoaderTest, ZkStateReaderTest, MoveReplicaHDFSTest, TestSimNodeLostTrigger, TestManagedSynonymGraphFilterFactory, TestSolrXml, AnalyticsMergeStrategyTest, JsonLoaderTest, UpdateRequestProcessorFactoryTest, InfixSuggestersTest, CustomTermsComponentTest, TestRestoreCore, TestPartialUpdateDeduplication, SolrLogAuditLoggerPluginTest, CloneFieldUpdateProcessorFactoryTest, V2ApiIntegrationTest, SolrCloudExampleTest, TestInPlaceUpdatesDistrib, TestFastOutputStream, DistribDocExpirationUpdateProcessorTest, ScheduledTriggerTest, JWTAuthPluginTest, TestUnifiedSolrHighlighter, DisMaxRequestHandlerTest, BaseCdcrDistributedZkTest, XsltUpdateRequestHandlerTest, TestRandomFaceting, TestZkChroot, StatsComponentTest, SolrInfoBeanTest, MetricsConfigTest, TestPivotHelperCode, LeaderVoteWaitTimeoutTest, TestGeoJSONResponseWriter, TestPseudoReturnFields, MigrateRouteKeyTest, TestCloudSchemaless, TestFieldCacheVsDocValues, MultiThreadedOCPTest, SecurityConfHandlerTest, TestDynamicLoading, HdfsCollectionsAPIDistributedZkTest, CloudExitableDirectoryReaderTest, TestOverriddenPrefixQueryForCustomFieldType, TestRecoveryHdfs, HLLSerializationTest, TestRebalanceLeaders, BasicFunctionalityTest, LeaderFailoverAfterPartitionTest, TestUnInvertedFieldException, HdfsChaosMonkeySafeLeaderTest, ChaosMonkeyNothingIsSafeTest, TestSweetSpotSimilarityFactory, TestChildDocTransformer, ZookeeperStatusHandlerTest, CollectionPropsTest, TestImpersonationWithHadoopAuth, CSVRequestHandlerTest, CollectionReloadTest, TestSegmentSorting, TestDistribDocBasedVersion, ReplicaListTransformerTest, CdcrOpsAndBoundariesTest, TestCollectionAPI, SystemLogListenerTest, PreAnalyzedFieldManagedSchemaCloudTest, TestAnalyzedSuggestions, TestHighlightDedupGrouping, ClassificationUpdateProcessorFactoryTest, NumericFieldsTest, TestClusterProperties, TestBinaryField, ReplaceNodeNoTargetTest, TestLeaderElectionZkExpiry, DistributedFacetPivotWhiteBoxTest, TestSearchPerf, TestScoreJoinQPScore, TestConfigOverlay, DistributedQueryComponentOptimizationTest, HttpTriggerListenerTest, HdfsThreadLeakTest, TestAtomicUpdateErrorCases, TestCorePropertiesReload, TestBadConfig, TestNumericTerms32, TimeZoneUtilsTest, NodeLostTriggerIntegrationTest, TestQuerySenderListener, BitVectorTest, ComputePlanActionTest, CdcrVersionReplicationTest, TestEmbeddedSolrServerConstructors, MetricTriggerTest, TestDistributedSearch, BlobRepositoryCloudTest, RestartWhileUpdatingTest, BufferStoreTest, TestComplexPhraseLeadingWildcard, SyncSliceTest, ProtectedTermFilterFactoryTest, TestOnReconnectListenerSupport, TestFreeTextSuggestions, TestSimClusterStateProvider, TemplateUpdateProcessorTest, FileUtilsTest, TestFieldCollectionResource, TestNumericTokenStream, TestJsonFacetsWithNestedObjects, MaxSizeAutoCommitTest, TestNonDefinedSimilarityFactory, TestFieldCacheReopen, IndexSchemaTest, SaslZkACLProviderTest, TestFilteredDocIdSet, SolrSlf4jReporterTest, TokenizerChainTest, DeleteLastCustomShardedReplicaTest, OverseerTest, TestLocalFSCloudBackupRestore, SubstringBytesRefFilterTest, EchoParamsTest, MinimalSchemaTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestTolerantSearch, TestTrie, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestLuceneIndexBackCompat, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, AddReplicaTest, AliasIntegrationTest, AsyncCallRequestStatusResponseTest, BasicDistributedZk2Test, BasicDistributedZkTest, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, CreateCollectionCleanupTest, DeleteNodeTest, DeleteReplicaTest, LeaderFailureAfterFreshStartTest, RecoveryZkTest, ReindexCollectionTest, TestCloudRecovery, TestConfigSetsAPIExclusivity, TestDownShardTolerantSearch, TestLockTree, TestPullReplica, TestPullReplicaErrorHandling, TestSolrCloudWithKerberosAlt, TestTolerantUpdateProcessorRandomCloud, ZkControllerTest, ZkFailoverTest, ZkNodePropsTest, ZkShardTermsTest, CollectionTooManyReplicasTest, TestCollectionsAPIViaSolrCloudCluster, TestRequestStatusCollectionAPI, NodeAddedTriggerIntegrationTest, NodeMarkersRegistrationTest, ScheduledMaintenanceTriggerTest, TriggerEventQueueTest, TestSimDistribStateManager, TestSimDistributedQueue, TestSimExecutePlanAction, TestSimPolicyCloud, CdcrReplicationHandlerTest, HdfsRecoverLeaseTest, RuleEngineTest, HdfsDirectoryFactoryTest, TestCustomStream, TestShardHandlerFactory, TestSolrConfigHandler, TestBlobHandler, TestReplicationHandlerDiskOverFlow, TestSQLHandler, DistributedFacetPivotSmallAdvancedTest, DistributedTermsComponentTest, SuggestComponentTest, TestDistributedStatsComponentCardinality, SolrJmxReporterCloudTest, SolrRrdBackendFactoryTest]
[junit4] Completed [647/851 (2!)] on J1 in 0.51s, 1 test, 1 failure <<< FAILURES!
[...truncated 43338 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/41/consoleText
[repro] Revision: cef83b3f862ce2a4ba80e50831dd03db817adf00
[repro] Repro line: ant test -Dtestcase=AutoScalingHandlerTest -Dtests.method=testReadApi -Dtests.seed=68C4D44EEE29746D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=pt-CV -Dtests.timezone=Asia/Bahrain -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=SolrRrdBackendFactoryTest -Dtests.method=testBasic -Dtests.seed=68C4D44EEE29746D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=rw -Dtests.timezone=America/St_Vincent -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] SolrRrdBackendFactoryTest
[repro] AutoScalingHandlerTest
[repro] ant compile-test
[...truncated 3581 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.SolrRrdBackendFactoryTest|*.AutoScalingHandlerTest" -Dtests.showOutput=onerror -Dtests.seed=68C4D44EEE29746D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=rw -Dtests.timezone=America/St_Vincent -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 140 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[repro] 0/5 failed: org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS] Lucene-Solr-BadApples-8.x-Linux (64bit/jdk1.8.0_172) -
Build # 43 - Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/43/
Java: 64bit/jdk1.8.0_172 -XX:+UseCompressedOops -XX:+UseG1GC
All tests passed
Build Log:
[...truncated 56382 lines...]
-documentation-lint:
[echo] checking for broken html...
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[echo] Checking for missing docs...
[exec]
[exec] build/docs/join/org/apache/lucene/search/join/SeekingTermSetTermsEnum.html
[exec] missing Constructors: SeekingTermSetTermsEnum-org.apache.lucene.index.TermsEnum-org.apache.lucene.util.BytesRefHash-int:A-
[exec]
[exec] Missing javadocs were found!
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/build.xml:642: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/build.xml:101: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/build.xml:142: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/build.xml:179: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/common-build.xml:2528: exec returned: 1
Total time: 75 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-BadApples-8.x-Linux (64bit/jdk1.8.0_172) -
Build # 42 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-8.x-Linux/42/
Java: 64bit/jdk1.8.0_172 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest
Error Message:
Timeout occurred while waiting response from server at: http://127.0.0.1:36657/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: http://127.0.0.1:36657/solr
at __randomizedtesting.SeedInfo.seed([279B3BD2DA1A3E38:D56F2CB09EBF330B]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:660)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1055)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:830)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:763)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
at org.apache.solr.cloud.LeaderVoteWaitTimeoutTest.basicTest(LeaderVoteWaitTimeoutTest.java:155)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:548)
... 49 more
Build Log:
[...truncated 14345 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/init-core-data-001
[junit4] 2> 1849541 WARN (SUITE-LeaderVoteWaitTimeoutTest-seed#[279B3BD2DA1A3E38]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1849548 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[279B3BD2DA1A3E38]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1849549 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[279B3BD2DA1A3E38]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1849549 INFO (SUITE-LeaderVoteWaitTimeoutTest-seed#[279B3BD2DA1A3E38]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1849556 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.SolrTestCaseJ4 ###Starting basicTest
[junit4] 2> 1849556 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001
[junit4] 2> 1849557 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1849572 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1849572 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1849672 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkTestServer start zk server on port:39987
[junit4] 2> 1849672 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39987
[junit4] 2> 1849672 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39987
[junit4] 2> 1849727 INFO (zkConnectionManagerCallback-7445-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849788 INFO (zkConnectionManagerCallback-7447-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849805 INFO (zkConnectionManagerCallback-7449-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1849806 WARN (jetty-launcher-7450-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1849806 WARN (jetty-launcher-7450-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1849806 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1849807 WARN (jetty-launcher-7450-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1849807 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1849808 WARN (jetty-launcher-7450-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1849808 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1849808 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1849808 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1849916 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1849916 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1849916 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1849925 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34813c4d{/solr,null,AVAILABLE}
[junit4] 2> 1849956 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1849956 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1849956 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1849963 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6660ae{/solr,null,AVAILABLE}
[junit4] 2> 1850028 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1850028 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1850028 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1850036 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e1d0fdc{/solr,null,AVAILABLE}
[junit4] 2> 1850052 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1850052 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1850053 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1850064 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68d4c608{/solr,null,AVAILABLE}
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@54116c26{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39149}
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@26f07e67{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36947}
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.e.j.s.Server Started @1850139ms
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.e.j.s.Server Started @1850139ms
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39149}
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36947}
[junit4] 2> 1850065 ERROR (jetty-launcher-7450-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1850065 ERROR (jetty-launcher-7450-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7f78ddec{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40425}
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.e.j.s.Server Started @1850140ms
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40425}
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:00.692Z
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:00.692Z
[junit4] 2> 1850065 ERROR (jetty-launcher-7450-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1850065 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:00.692Z
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@d03bfec{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44121}
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.e.j.s.Server Started @1850140ms
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44121}
[junit4] 2> 1850066 ERROR (jetty-launcher-7450-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1850066 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:00.693Z
[junit4] 2> 1850144 INFO (zkConnectionManagerCallback-7454-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850145 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1850155 INFO (zkConnectionManagerCallback-7456-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850156 INFO (zkConnectionManagerCallback-7452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850156 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1850156 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1850178 INFO (zkConnectionManagerCallback-7458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850184 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1850206 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1850228 WARN (jetty-launcher-7450-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@76c8a596[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850265 WARN (jetty-launcher-7450-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6bd41afb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850266 INFO (jetty-launcher-7450-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1850295 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1850332 WARN (jetty-launcher-7450-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2a80ca8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850343 INFO (zkConnectionManagerCallback-7468-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850359 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1850372 WARN (jetty-launcher-7450-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@68a1dd4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850374 INFO (jetty-launcher-7450-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1850390 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1850420 WARN (jetty-launcher-7450-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@50f75215[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850428 INFO (zkConnectionManagerCallback-7474-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850448 WARN (jetty-launcher-7450-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6526802[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850510 WARN (jetty-launcher-7450-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2e30a9c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850511 INFO (jetty-launcher-7450-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1850524 INFO (zkConnectionManagerCallback-7477-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850551 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1010a4d2e950009, likely client has closed socket
[junit4] 2> 1850592 WARN (jetty-launcher-7450-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5eae3a3c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1850593 INFO (jetty-launcher-7450-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1850635 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39149_solr
[junit4] 2> 1850635 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.Overseer Overseer (id=72350395624914952-127.0.0.1:39149_solr-n_0000000000) starting
[junit4] 2> 1850636 INFO (zkConnectionManagerCallback-7486-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850680 INFO (zkConnectionManagerCallback-7489-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850684 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1010a4d2e95000a, likely client has closed socket
[junit4] 2> 1850688 INFO (zkConnectionManagerCallback-7492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850695 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36947_solr
[junit4] 2> 1850756 INFO (zkCallback-7473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1850792 INFO (zkCallback-7488-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1850804 INFO (zkConnectionManagerCallback-7494-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850810 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1850813 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44121_solr as DOWN
[junit4] 2> 1850814 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1850814 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44121_solr
[junit4] 2> 1850868 INFO (zkConnectionManagerCallback-7506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850873 INFO (zkCallback-7488-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1850873 INFO (zkCallback-7473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1850874 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1850875 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1850875 INFO (zkCallback-7493-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1850888 INFO (zkConnectionManagerCallback-7501-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850900 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1850900 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1850911 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1850941 INFO (zkConnectionManagerCallback-7508-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1850944 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36947.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1850954 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1850956 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40425_solr as DOWN
[junit4] 2> 1850956 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1850956 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40425_solr
[junit4] 2> 1850957 INFO (zkCallback-7473-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1850957 INFO (zkCallback-7488-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1850962 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36947.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1850962 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36947.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1850962 INFO (jetty-launcher-7450-thread-1) [n:127.0.0.1:36947_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node1/.
[junit4] 2> 1850975 INFO (OverseerStateUpdate-72350395624914952-127.0.0.1:39149_solr-n_0000000000) [n:127.0.0.1:39149_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39149_solr
[junit4] 2> 1850993 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39149_solr as DOWN
[junit4] 2> 1850994 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1850994 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39149_solr
[junit4] 2> 1850998 INFO (zkCallback-7488-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851018 INFO (zkCallback-7493-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1851033 INFO (zkCallback-7507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1851092 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1851095 INFO (zkConnectionManagerCallback-7516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851096 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1851097 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1851099 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851104 INFO (zkCallback-7505-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1851123 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1851138 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44121.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851146 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44121.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851146 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44121.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851147 INFO (jetty-launcher-7450-thread-2) [n:127.0.0.1:44121_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node2/.
[junit4] 2> 1851160 INFO (zkConnectionManagerCallback-7524-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851192 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1851197 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1851207 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1851218 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1851221 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40425.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851231 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39149.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851241 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39149.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851241 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39149.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851242 INFO (jetty-launcher-7450-thread-4) [n:127.0.0.1:39149_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node4/.
[junit4] 2> 1851245 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40425.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851245 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40425.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851245 INFO (jetty-launcher-7450-thread-3) [n:127.0.0.1:40425_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node3/.
[junit4] 2> 1851357 INFO (zkConnectionManagerCallback-7531-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851359 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1851360 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1851368 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=332724532
[junit4] 2> 1851368 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:36947_solr
[junit4] 2> 1851372 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 1851372 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:36947_solr as DOWN
[junit4] 2> 1851374 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1851380 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1851380 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a9acbcb: rootName = solr_36947, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@297b23cb
[junit4] 2> 1851386 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1851386 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53b3f829: rootName = solr_36947, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@15938518
[junit4] 2> 1851390 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1851390 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2933e9b2: rootName = solr_36947, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4deee598
[junit4] 2> 1851390 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1851421 INFO (zkCallback-7505-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851421 INFO (zkCallback-7493-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851421 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851428 INFO (zkCallback-7523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851436 INFO (zkCallback-7507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851436 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851440 INFO (zkCallback-7515-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851440 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1851442 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@26f07e67{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1851442 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@68d4c608{/solr,null,UNAVAILABLE}
[junit4] 2> 1851443 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1851443 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1851443 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1851444 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1851444 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1851484 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1851484 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1851484 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1851497 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3a2170b2{/solr,null,AVAILABLE}
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@50291f22{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42767}
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server Started @1851573ms
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36657}
[junit4] 2> 1851499 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1851499 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:02.126Z
[junit4] 2> 1851516 INFO (zkConnectionManagerCallback-7536-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851516 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1851755 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1851768 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2ab307b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1851778 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4b17ae5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1851779 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1851796 INFO (zkConnectionManagerCallback-7543-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851816 INFO (zkConnectionManagerCallback-7545-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851818 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1851821 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36657_solr as DOWN
[junit4] 2> 1851822 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1851822 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36657_solr
[junit4] 2> 1851823 INFO (zkCallback-7515-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851823 INFO (zkCallback-7523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851823 INFO (zkCallback-7493-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851823 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851823 INFO (zkCallback-7507-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851823 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851823 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851846 INFO (zkCallback-7544-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1851860 INFO (zkConnectionManagerCallback-7552-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1851864 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1851870 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1851880 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1851896 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36657.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851907 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36657.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851907 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36657.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1851908 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [n:127.0.0.1:36657_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node1/.
[junit4] 2> 1851972 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:42767/solr. Proxy: http://127.0.0.1:36657/solr
[junit4] 2> 1851972 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=878744653
[junit4] 2> 1851972 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44121_solr
[junit4] 2> 1851972 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 1851972 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:44121_solr as DOWN
[junit4] 2> 1851974 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1851974 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73ab2606: rootName = solr_44121, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4a6c899e
[junit4] 2> 1851980 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1851980 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@659d21f9: rootName = solr_44121, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@15938518
[junit4] 2> 1851984 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1851984 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ede5fc0: rootName = solr_44121, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4deee598
[junit4] 2> 1851984 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1852021 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852021 INFO (zkCallback-7544-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852021 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852022 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852023 INFO (zkCallback-7523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852024 INFO (zkCallback-7507-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852032 INFO (zkCallback-7551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852034 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@d03bfec{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1852034 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@34813c4d{/solr,null,UNAVAILABLE}
[junit4] 2> 1852034 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1852035 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1852035 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1852036 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1852036 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1852072 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1852072 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1852072 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1852084 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c49adf0{/solr,null,AVAILABLE}
[junit4] 2> 1852084 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@772d814f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39977}
[junit4] 2> 1852084 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server Started @1852159ms
[junit4] 2> 1852084 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44977}
[junit4] 2> 1852085 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1852085 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1852085 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1852085 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1852085 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1852085 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:02.712Z
[junit4] 2> 1852108 INFO (zkConnectionManagerCallback-7558-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1852108 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1852178 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1852179 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4beffb8f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1852213 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@b2e732b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1852214 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1852243 INFO (zkConnectionManagerCallback-7565-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1852253 INFO (zkConnectionManagerCallback-7567-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1852282 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1852284 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:44977_solr as DOWN
[junit4] 2> 1852284 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1852284 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44977_solr
[junit4] 2> 1852285 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852285 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852285 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852285 INFO (zkCallback-7544-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852285 INFO (zkCallback-7523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852295 INFO (zkCallback-7551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852295 INFO (zkCallback-7507-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852304 INFO (zkCallback-7566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1852331 INFO (zkConnectionManagerCallback-7574-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1852332 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1852336 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1852351 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1852376 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44977.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1852385 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44977.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1852385 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44977.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1852386 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node2/.
[junit4] 2> 1852435 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:39977/solr. Proxy: http://127.0.0.1:44977/solr
[junit4] 2> 1852436 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1508717479
[junit4] 2> 1852436 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:40425_solr
[junit4] 2> 1852437 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 1852437 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:40425_solr as DOWN
[junit4] 2> 1852439 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1852439 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12583d7d: rootName = solr_40425, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5bc2683b
[junit4] 2> 1852443 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1852443 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2d768022: rootName = solr_40425, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@15938518
[junit4] 2> 1852445 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1852445 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6581d98d: rootName = solr_40425, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4deee598
[junit4] 2> 1852445 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1852462 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852462 INFO (zkCallback-7551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852462 INFO (zkCallback-7544-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852463 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852463 INFO (zkCallback-7523-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852468 INFO (zkCallback-7566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852468 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852479 INFO (zkCallback-7573-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1852480 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7f78ddec{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1852482 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6660ae{/solr,null,UNAVAILABLE}
[junit4] 2> 1852482 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1852483 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1852483 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1852483 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1852483 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1852572 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1852572 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1852572 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1852576 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@52fe3d15{/solr,null,AVAILABLE}
[junit4] 2> 1852576 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9594a65{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44247}
[junit4] 2> 1852576 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server Started @1852651ms
[junit4] 2> 1852576 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46451}
[junit4] 2> 1852576 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1852576 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1852577 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1852577 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1852577 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1852577 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:03.204Z
[junit4] 2> 1852607 INFO (zkConnectionManagerCallback-7580-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1852611 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1852957 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1852976 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@138ff55b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1853012 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@199149aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1853013 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1853063 INFO (zkConnectionManagerCallback-7587-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1853092 INFO (zkConnectionManagerCallback-7589-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1853095 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1853096 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:46451_solr as DOWN
[junit4] 2> 1853097 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1853097 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46451_solr
[junit4] 2> 1853097 INFO (zkCallback-7473-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853097 INFO (zkCallback-7566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853097 INFO (zkCallback-7500-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853097 INFO (zkCallback-7573-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853097 INFO (zkCallback-7544-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853103 INFO (zkCallback-7551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853103 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853116 INFO (zkCallback-7588-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1853156 INFO (zkConnectionManagerCallback-7596-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1853163 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1853176 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1853207 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1853232 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46451.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1853245 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46451.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1853245 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46451.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1853246 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node3/.
[junit4] 2> 1853402 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:44247/solr. Proxy: http://127.0.0.1:46451/solr
[junit4] 2> 1853402 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=397271699
[junit4] 2> 1853402 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39149_solr
[junit4] 2> 1853402 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CoreContainer Publish this node as DOWN...
[junit4] 2> 1853402 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:39149_solr as DOWN
[junit4] 2> 1853420 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1853420 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6896e0be: rootName = solr_39149, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@2e837e2f
[junit4] 2> 1853426 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1853426 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38c821e8: rootName = solr_39149, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@15938518
[junit4] 2> 1853429 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1853429 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21838aca: rootName = solr_39149, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4deee598
[junit4] 2> 1853429 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1853459 INFO (closeThreadPool-7600-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72350395624914952-127.0.0.1:39149_solr-n_0000000000) closing
[junit4] 2> 1853484 INFO (OverseerStateUpdate-72350395624914952-127.0.0.1:39149_solr-n_0000000000) [n:127.0.0.1:39149_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39149_solr
[junit4] 2> 1853504 INFO (OverseerAutoScalingTriggerThread-72350395624914952-127.0.0.1:39149_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1853517 INFO (closeThreadPool-7600-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72350395624914952-127.0.0.1:39149_solr-n_0000000000) closing
[junit4] 2> 1853529 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.Overseer Overseer (id=72350395624914952-127.0.0.1:39149_solr-n_0000000000) closing
[junit4] 2> 1853531 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1010a4d2e950008, likely client has closed socket
[junit4] 2> 1853531 INFO (zkCallback-7573-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853531 INFO (zkCallback-7588-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853531 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853531 INFO (zkCallback-7544-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36657_solr
[junit4] 2> 1853531 INFO (zkCallback-7544-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853531 INFO (zkCallback-7566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853531 INFO (zkCallback-7551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853532 INFO (zkCallback-7544-thread-2) [n:127.0.0.1:36657_solr ] o.a.s.c.Overseer Overseer (id=72350395624914966-127.0.0.1:36657_solr-n_0000000004) starting
[junit4] 2> 1853544 INFO (zkCallback-7595-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1853557 INFO (OverseerStateUpdate-72350395624914966-127.0.0.1:36657_solr-n_0000000004) [n:127.0.0.1:36657_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:36657_solr
[junit4] 2> 1853562 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@54116c26{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1853580 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e1d0fdc{/solr,null,UNAVAILABLE}
[junit4] 2> 1853580 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1853581 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1853581 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1853581 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1853581 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1853594 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1853594 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1853594 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1853594 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@607ab74e{/solr,null,AVAILABLE}
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@edabbf0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40439}
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.s.Server Started @1853694ms
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40883}
[junit4] 2> 1853620 ERROR (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1853620 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-10T10:47:04.247Z
[junit4] 2> 1853678 INFO (zkConnectionManagerCallback-7602-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1853685 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1854034 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1854040 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2f2a7348[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1854060 WARN (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3570c283[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1854061 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39987/solr
[junit4] 2> 1854107 INFO (zkConnectionManagerCallback-7609-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1854125 INFO (zkConnectionManagerCallback-7611-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1854127 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1854129 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:40883_solr as DOWN
[junit4] 2> 1854129 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1854129 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40883_solr
[junit4] 2> 1854130 INFO (zkCallback-7530-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854130 INFO (zkCallback-7566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854130 INFO (zkCallback-7588-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854130 INFO (zkCallback-7573-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854130 INFO (zkCallback-7551-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854130 INFO (zkCallback-7544-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854144 INFO (zkCallback-7595-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854149 INFO (zkCallback-7610-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1854180 INFO (zkConnectionManagerCallback-7618-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1854196 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1854196 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39987/solr ready
[junit4] 2> 1854235 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1854252 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40883.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1854284 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40883.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1854284 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40883.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3283a265
[junit4] 2> 1854285 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderVoteWaitTimeoutTest_279B3BD2DA1A3E38-001/tempDir-001/node4/.
[junit4] 2> 1854407 INFO (TEST-LeaderVoteWaitTimeoutTest.basicTest-seed#[279B3BD2DA1A3E38]) [ ] o.a.s.c.LeaderVoteWaitTimeoutTest Adding proxy for URL: http://127.0.0.1:40439/solr. Proxy: http://127.0.0.1:40883/
[...truncated too long message...]
kins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/top-level-ivy-settings.xml
resolve:
init:
compile-lucene-core:
compile-codecs:
-check-git-state:
-git-cleanroot:
-copy-git-state:
git-autoclean:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/top-level-ivy-settings.xml
resolve:
common.init:
compile-lucene-core:
init:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
compile-core:
-clover.disable:
-clover.load:
-clover.classpath:
-clover.setup:
clover:
common.compile-core:
compile-core:
common.compile-test:
[mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/classes/test
[javac] Compiling 959 source files to /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/classes/test
[javac] Note: Some input files use or override a deprecated API.
[javac] Note: Recompile with -Xlint:deprecation for details.
[javac] Note: Some input files use unchecked or unsafe operations.
[javac] Note: Recompile with -Xlint:unchecked for details.
[javac] Creating empty /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/classes/test/org/apache/solr/cloud/autoscaling/sim/package-info.class
[copy] Copying 1 file to /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/classes/test
common-solr.compile-test:
compile-test:
BUILD SUCCESSFUL
Total time: 1 minute 39 seconds
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.LeaderVoteWaitTimeoutTest" -Dtests.showOutput=onerror -Dtests.seed=279B3BD2DA1A3E38 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=es-BO -Dtests.timezone=Canada/Newfoundland -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
Buildfile: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/core/build.xml
-clover.disable:
ivy-configure:
[ivy:configure] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:configure] :: loading settings :: file = /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/lucene/top-level-ivy-settings.xml
install-junit4-taskdef:
ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.
-ivy-fail-disallowed-ivy-version:
ivy-fail:
resolve-groovy:
[ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working
[ivy:cachepath] confs: [default]
[ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public
[ivy:cachepath] :: resolution report :: resolve 136ms :: artifacts dl 1ms
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-init-totals:
-test:
[mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: 279B3BD2DA1A3E38
[mkdir] Created dir: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/temp
[junit4] <JUnit4> says Привет! Master seed: 279B3BD2DA1A3E38
[junit4] Executing 5 suites with 3 JVMs.
[junit4]
[junit4] Started J2 PID(20025@serv1.sd-datasolutions.de).
[junit4] Started J0 PID(20024@serv1.sd-datasolutions.de).
[junit4] Started J1 PID(20026@serv1.sd-datasolutions.de).
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 33.4s J1 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] OK 30.5s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] Completed [1/5] on J1 in 68.25s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 34.1s J2 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] OK 31.8s J2 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] Completed [2/5] on J2 in 70.15s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 35.4s J0 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] OK 30.7s J0 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] Completed [3/5] on J0 in 70.20s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 15.8s J1 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] OK 27.4s J1 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] Completed [4/5] on J1 in 44.15s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[junit4] OK 16.4s J2 | LeaderVoteWaitTimeoutTest.basicTest
[junit4] OK 26.7s J2 | LeaderVoteWaitTimeoutTest.testMostInSyncReplicasCanWinElection
[junit4] Completed [5/5] on J2 in 43.73s, 2 tests
[junit4]
[junit4] JVM J0: 1.24 .. 72.98 = 71.75s
[junit4] JVM J1: 1.27 .. 115.49 = 114.22s
[junit4] JVM J2: 1.19 .. 116.58 = 115.39s
[junit4] Execution time total: 1 minute 56 seconds
[junit4] Tests summary: 5 suites, 10 tests
[junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J2 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J2/temp])
[junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 275.99s | org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest
[junit4:tophints] 208.99s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 184.63s | org.apache.solr.cloud.BasicDistributedZkTest
[junit4:tophints] 174.49s | org.apache.solr.search.facet.TestJsonFacets
[junit4:tophints] 116.97s | org.apache.solr.update.SoftAutoCommitTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 2 minutes 1 second
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.LeaderVoteWaitTimeoutTest
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ 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
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2801)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2680)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2661)
at hudson.FilePath.act(FilePath.java:1074)
at hudson.FilePath.act(FilePath.java:1057)
at hudson.FilePath.validateAntFileMask(FilePath.java:2659)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1841)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[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=addb3977fd735bd852aad2c737c27d8888f70dca, workspace=/home/jenkins/workspace/Lucene-Solr-BadApples-8.x-Linux
[WARNINGS] Computing warning deltas based on reference build #41
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