You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/21 20:13:47 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-12.0.2) - Build #
26735 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/26735/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.cloud.TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast
Error Message:
expected:<503> but was:<510>
Stack Trace:
java.lang.AssertionError: expected:<503> but was:<510>
at __randomizedtesting.SeedInfo.seed([714F0A74DDCC17A6:A29223DE7AFD7EDA]: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.TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast(TestQueryingOnDownCollection.java:98)
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:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:835)
FAILED: org.apache.solr.cloud.TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast
Error Message:
Request should fail after trying all replica nodes once
Stack Trace:
junit.framework.AssertionFailedError: Request should fail after trying all replica nodes once
at __randomizedtesting.SeedInfo.seed([714F0A74DDCC17A6:A29223DE7AFD7EDA]:0)
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2749)
at org.apache.solr.cloud.TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast(TestQueryingOnDownCollection.java:91)
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:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
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:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:835)
Build Log:
[...truncated 14588 lines...]
[junit4] Suite: org.apache.solr.cloud.TestQueryingOnDownCollection
[junit4] 2> 759698 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 759698 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 759698 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/data-dir-81-001
[junit4] 2> 759698 WARN (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 759698 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 759699 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 759699 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001
[junit4] 2> 759699 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 759699 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 759699 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 759799 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 44793
[junit4] 2> 759799 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:44793
[junit4] 2> 759799 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44793
[junit4] 2> 759799 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44793
[junit4] 2> 759803 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759804 INFO (zkConnectionManagerCallback-10407-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759804 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759816 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759821 INFO (zkConnectionManagerCallback-10409-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759821 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759822 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759825 INFO (zkConnectionManagerCallback-10411-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759825 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759942 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 759942 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 759942 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 759942 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 759942 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 759942 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 759943 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 759943 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 759943 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@561703c{/solr,null,AVAILABLE}
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 759958 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 759959 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c62bb66{/solr,null,AVAILABLE}
[junit4] 2> 759959 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e609b5c{/solr,null,AVAILABLE}
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2438eddb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:38781}
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.e.j.s.Server Started @759988ms
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7876b8b6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42787}
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38781}
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.e.j.s.Server Started @759988ms
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42787}
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@628cec98{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45763}
[junit4] 2> 759962 ERROR (jetty-launcher-10412-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.e.j.s.Server Started @759989ms
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 759962 ERROR (jetty-launcher-10412-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45763}
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-21T19:54:09.126451Z
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-21T19:54:09.126457Z
[junit4] 2> 759962 ERROR (jetty-launcher-10412-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 759962 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-21T19:54:09.126563Z
[junit4] 2> 759964 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759964 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759964 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 759966 INFO (zkConnectionManagerCallback-10415-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759966 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759966 INFO (zkConnectionManagerCallback-10418-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759966 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759966 INFO (zkConnectionManagerCallback-10416-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 759966 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 759967 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 759967 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 759967 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 760072 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 760073 WARN (jetty-launcher-10412-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d50d3c4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760073 WARN (jetty-launcher-10412-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d50d3c4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760074 WARN (jetty-launcher-10412-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3e32e159[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760074 WARN (jetty-launcher-10412-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3e32e159[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760075 INFO (jetty-launcher-10412-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44793/solr
[junit4] 2> 760076 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760077 INFO (zkConnectionManagerCallback-10429-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 760077 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760115 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42787_solr
[junit4] 2> 760115 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.Overseer Overseer (id=72082977205190662-127.0.0.1:42787_solr-n_0000000000) starting
[junit4] 2> 760117 INFO (OverseerStateUpdate-72082977205190662-127.0.0.1:42787_solr-n_0000000000) [n:127.0.0.1:42787_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42787_solr
[junit4] 2> 760118 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42787_solr
[junit4] 2> 760118 INFO (zkCallback-10428-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 760119 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 760119 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 760126 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 760140 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 760154 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42787.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760161 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42787.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760161 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42787.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760162 INFO (jetty-launcher-10412-thread-3) [n:127.0.0.1:42787_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node3/.
[junit4] 2> 760173 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 760174 WARN (jetty-launcher-10412-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@44954b0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760174 WARN (jetty-launcher-10412-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@44954b0f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760178 WARN (jetty-launcher-10412-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@27a4a53a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760178 WARN (jetty-launcher-10412-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@27a4a53a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760179 INFO (jetty-launcher-10412-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44793/solr
[junit4] 2> 760182 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760184 INFO (zkConnectionManagerCallback-10452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 760184 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760186 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 760187 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45763_solr as DOWN
[junit4] 2> 760188 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 760188 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45763_solr
[junit4] 2> 760188 INFO (zkCallback-10428-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 760188 INFO (zkCallback-10451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 760189 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 760189 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 760189 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 760194 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 760206 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45763.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760211 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45763.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760211 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45763.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760212 INFO (jetty-launcher-10412-thread-2) [n:127.0.0.1:45763_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node2/.
[junit4] 2> 760273 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 760273 WARN (jetty-launcher-10412-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5461efb2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760273 WARN (jetty-launcher-10412-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5461efb2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760276 WARN (jetty-launcher-10412-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@77e1ae20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760276 WARN (jetty-launcher-10412-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@77e1ae20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 760276 INFO (jetty-launcher-10412-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44793/solr
[junit4] 2> 760277 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760278 INFO (zkConnectionManagerCallback-10471-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 760278 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760280 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 760281 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38781_solr as DOWN
[junit4] 2> 760281 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 760282 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38781_solr
[junit4] 2> 760282 INFO (zkCallback-10428-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 760282 INFO (zkCallback-10451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 760283 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 760283 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 760283 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 760286 INFO (zkCallback-10470-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 760290 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 760304 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38781.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760312 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38781.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760312 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38781.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760313 INFO (jetty-launcher-10412-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node1/.
[junit4] 2> 760318 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 760321 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 760322 INFO (zkConnectionManagerCallback-10484-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 760322 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 760322 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 760323 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44793/solr ready
[junit4] 2> 760341 INFO (TEST-TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast-seed#[714F0A74DDCC17A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testQueryToDownCollectionShouldFailFast
[junit4] 2> 760346 INFO (qtp1816858465-19298) [n:127.0.0.1:45763_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=infected&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 760347 INFO (OverseerThreadFactory-10436-thread-1-processing-n:127.0.0.1:42787_solr) [n:127.0.0.1:42787_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection infected
[junit4] 2> 760465 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 760465 INFO (qtp1816858465-19302) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:38781_solr / MFwwDQYJKoZIhvcNAQEBBQADSwAwSAJBAMgmSVfl+E2Nj/oKAP2TceWh17pk6Ugsw5A5nLH+OeMB/WeWJZg/NEDda8SXfQDEVRmw5P+2IZypPASzfCrc6yECAwEAAQ==
[junit4] 2> 760467 INFO (qtp1816858465-19302) [n:127.0.0.1:45763_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 760469 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 760469 INFO (qtp1991329808-19305) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:38781_solr / MFwwDQYJKoZIhvcNAQEBBQADSwAwSAJBAMgmSVfl+E2Nj/oKAP2TceWh17pk6Ugsw5A5nLH+OeMB/WeWJZg/NEDda8SXfQDEVRmw5P+2IZypPASzfCrc6yECAwEAAQ==
[junit4] 2> 760470 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 760470 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 760471 INFO (qtp721356532-19307) [n:127.0.0.1:38781_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 760471 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:38781_solr / MFwwDQYJKoZIhvcNAQEBBQADSwAwSAJBAMgmSVfl+E2Nj/oKAP2TceWh17pk6Ugsw5A5nLH+OeMB/WeWJZg/NEDda8SXfQDEVRmw5P+2IZypPASzfCrc6yECAwEAAQ==
[junit4] 2> 760472 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 760474 INFO (qtp1816858465-19302) [n:127.0.0.1:45763_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 760475 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 760476 INFO (qtp721356532-19307) [n:127.0.0.1:38781_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 760478 INFO (OverseerStateUpdate-72082977205190662-127.0.0.1:42787_solr-n_0000000000) [n:127.0.0.1:42787_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"infected",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"infected_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38781/solr",
[junit4] 2> "node_name":"127.0.0.1:38781_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 760478 INFO (OverseerStateUpdate-72082977205190662-127.0.0.1:42787_solr-n_0000000000) [n:127.0.0.1:42787_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"infected",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"infected_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:42787/solr",
[junit4] 2> "node_name":"127.0.0.1:42787_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 760685 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr x:infected_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=infected_shard1_replica_n1&action=CREATE&numShards=2&collection=infected&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 760685 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr x:infected_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=infected_shard2_replica_n2&action=CREATE&numShards=2&collection=infected&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 760702 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 760704 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 760710 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 760712 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 760712 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'infected_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 760712 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42787.solr.core.infected.shard2.replica_n2' (registry 'solr.core.infected.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760712 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SolrCore [[infected_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node3/./infected_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node3/./infected_shard2_replica_n2/data/]
[junit4] 2> 760712 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 760714 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 760714 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'infected_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 760715 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38781.solr.core.infected.shard1.replica_n1' (registry 'solr.core.infected.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777a77bc
[junit4] 2> 760715 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SolrCore [[infected_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node1/./infected_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001/node1/./infected_shard1_replica_n1/data/]
[junit4] 2> 760747 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 760747 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 760748 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 760748 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 760750 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 760750 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 760750 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 760751 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667331103040471040
[junit4] 2> 760752 INFO (searcherExecutor-10488-thread-1-processing-n:127.0.0.1:38781_solr x:infected_shard1_replica_n1 c:infected s:shard1 r:core_node3) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SolrCore [infected_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'infected'
[junit4] 2> 760754 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/infected/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 760754 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/infected/leaders/shard1
[junit4] 2> 760754 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 760754 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 760755 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 760755 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 760755 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 760755 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 760755 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:38781/solr/infected_shard1_replica_n1/
[junit4] 2> 760756 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 760756 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:38781/solr/infected_shard1_replica_n1/ has no replicas
[junit4] 2> 760756 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/infected/leaders/shard1/leader after winning as /collections/infected/leader_elect/shard1/election/72082977205190664-core_node3-n_0000000000
[junit4] 2> 760756 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38781/solr/infected_shard1_replica_n1/ shard1
[junit4] 2> 760757 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 760757 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 760757 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 760758 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667331103047811072
[junit4] 2> 760762 INFO (searcherExecutor-10486-thread-1-processing-n:127.0.0.1:42787_solr x:infected_shard2_replica_n2 c:infected s:shard2 r:core_node4) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SolrCore [infected_shard2_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'infected'
[junit4] 2> 760763 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/infected/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 760763 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/infected/leaders/shard2
[junit4] 2> 760764 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 760764 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 760764 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42787/solr/infected_shard2_replica_n2/
[junit4] 2> 760764 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 760764 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:42787/solr/infected_shard2_replica_n2/ has no replicas
[junit4] 2> 760764 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/infected/leaders/shard2/leader after winning as /collections/infected/leader_elect/shard2/election/72082977205190662-core_node4-n_0000000000
[junit4] 2> 760765 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42787/solr/infected_shard2_replica_n2/ shard2
[junit4] 2> 760866 INFO (zkCallback-10428-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760866 INFO (zkCallback-10470-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760866 INFO (zkCallback-10428-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760866 INFO (zkCallback-10428-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760866 INFO (zkCallback-10470-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760866 INFO (zkCallback-10470-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760870 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 760870 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 760872 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=infected_shard2_replica_n2&action=CREATE&numShards=2&collection=infected&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=186
[junit4] 2> 760872 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=infected_shard1_replica_n1&action=CREATE&numShards=2&collection=infected&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=187
[junit4] 2> 760873 INFO (qtp1816858465-19298) [n:127.0.0.1:45763_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 760973 INFO (zkCallback-10428-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (zkCallback-10451-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (zkCallback-10428-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (zkCallback-10428-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (zkCallback-10470-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (zkCallback-10470-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (zkCallback-10428-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760973 INFO (qtp1816858465-19298) [n:127.0.0.1:45763_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=infected&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=628
[junit4] 2> 760973 INFO (zkCallback-10470-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760979 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/infected/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 760979 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/infected/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 760979 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [infected_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=infected:3&wt=javabin&version=2}{add=[0 (1667331103276400640), 1 (1667331103277449216), 4 (1667331103277449217), 8 (1667331103277449218), 10 (1667331103277449219), 11 (1667331103277449220), 12 (1667331103277449221), 13 (1667331103277449222), 14 (1667331103277449223), 15 (1667331103277449224), ... (56 adds)]} 0 4
[junit4] 2> 760979 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [infected_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=infected:3&wt=javabin&version=2}{add=[2 (1667331103276400640), 3 (1667331103278497792), 5 (1667331103278497793), 6 (1667331103278497794), 7 (1667331103278497795), 9 (1667331103278497796), 17 (1667331103278497797), 18 (1667331103278497798), 19 (1667331103278497799), 21 (1667331103278497800), ... (44 adds)]} 0 4
[junit4] 2> 760979 INFO (zkCallback-10470-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 760994 INFO (qtp1991329808-19331) [n:127.0.0.1:42787_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 760994 INFO (qtp721356532-19307) [n:127.0.0.1:38781_solr ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:42787_solr / MFwwDQYJKoZIhvcNAQEBBQADSwAwSAJBAMgmSVfl+E2Nj/oKAP2TceWh17pk6Ugsw5A5nLH+OeMB/WeWJZg/NEDda8SXfQDEVRmw5P+2IZypPASzfCrc6yECAwEAAQ==
[junit4] 2> 761001 INFO (searcherExecutor-10488-thread-1-processing-n:127.0.0.1:38781_solr x:infected_shard1_replica_n1 c:infected s:shard1 r:core_node3) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.SolrCore [infected_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'infected'
[junit4] 2> 761001 INFO (qtp721356532-19307) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [infected_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:42787/solr/infected_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6
[junit4] 2> 761005 INFO (searcherExecutor-10486-thread-1-processing-n:127.0.0.1:42787_solr x:infected_shard2_replica_n2 c:infected s:shard2 r:core_node4) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.SolrCore [infected_shard2_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'infected'
[junit4] 2> 761006 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [infected_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=infected:3&commit=true&wt=javabin&version=2}{commit=} 0 25
[junit4] 2> 761018 INFO (zkCallback-10470-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761018 INFO (zkCallback-10470-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761018 INFO (zkCallback-10470-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761018 INFO (zkCallback-10470-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761021 INFO (zkCallback-10428-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761021 INFO (zkCallback-10428-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761025 INFO (zkCallback-10428-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761025 INFO (zkCallback-10428-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [3])
[junit4] 2> 761039 INFO (qtp1991329808-19341) [n:127.0.0.1:42787_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 761039 INFO (qtp721356532-19303) [n:127.0.0.1:38781_solr c:infected s:shard1 r:core_node3 x:infected_shard1_replica_n1 ] o.a.s.c.S.Request [infected_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=http://127.0.0.1:38781/solr/infected_shard1_replica_n1/&rows=0&version=2&q=*:*&omitHeader=false&NOW=1590090850188&isShard=true&wt=javabin} hits=56 status=0 QTime=0
[junit4] 2> 761039 INFO (qtp1991329808-19331) [n:127.0.0.1:42787_solr ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:42787_solr / MFwwDQYJKoZIhvcNAQEBBQADSwAwSAJBAMgmSVfl+E2Nj/oKAP2TceWh17pk6Ugsw5A5nLH+OeMB/WeWJZg/NEDda8SXfQDEVRmw5P+2IZypPASzfCrc6yECAwEAAQ==
[junit4] 2> 761040 INFO (qtp1991329808-19331) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.S.Request [infected_shard2_replica_n2] webapp=/solr path=/select params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=http://127.0.0.1:42787/solr/infected_shard2_replica_n2/&rows=0&version=2&q=*:*&omitHeader=false&NOW=1590090850188&isShard=true&wt=javabin} hits=44 status=0 QTime=0
[junit4] 2> 761040 INFO (qtp1991329808-19305) [n:127.0.0.1:42787_solr c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n2 ] o.a.s.c.S.Request [infected_shard2_replica_n2] webapp=/solr path=/select params={q=*:*&rows=0&wt=javabin&version=2} hits=100 status=0 QTime=15
[junit4] 2> 761041 INFO (TEST-TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast-seed#[714F0A74DDCC17A6]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testQueryToDownCollectionShouldFailFast
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestQueryingOnDownCollection -Dtests.method=testQueryToDownCollectionShouldFailFast -Dtests.seed=714F0A74DDCC17A6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt-BR -Dtests.timezone=Asia/Karachi -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.72s J1 | TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Request should fail after trying all replica nodes once
[junit4] > at __randomizedtesting.SeedInfo.seed([714F0A74DDCC17A6:A29223DE7AFD7EDA]:0)
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2749)
[junit4] > at org.apache.solr.cloud.TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast(TestQueryingOnDownCollection.java:91)
[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> 761143 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1947467742
[junit4] 2> 761143 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42787_solr
[junit4] 2> 761143 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 761143 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:42787_solr as DOWN
[junit4] 2> 761145 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1857108231
[junit4] 2> 761146 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45763_solr
[junit4] 2> 761146 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 761146 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:45763_solr as DOWN
[junit4] 2> 761154 INFO (coreCloseExecutor-10504-thread-1) [n:127.0.0.1:42787_solr ] o.a.s.c.SolrCore [infected_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@1d256408
[junit4] 2> 761154 INFO (coreCloseExecutor-10504-thread-1) [n:127.0.0.1:42787_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.infected.shard2.replica_n2 tag=SolrCore@1d256408
[junit4] 2> 761154 INFO (coreCloseExecutor-10504-thread-1) [n:127.0.0.1:42787_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f582953: rootName = solr_42787, domain = solr.core.infected.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.infected.shard2.replica_n2/com.codahale.metrics.MetricRegistry@73292756
[junit4] 2> 761159 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2082905993
[junit4] 2> 761159 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:38781_solr
[junit4] 2> 761159 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 761159 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:38781_solr as DOWN
[junit4] 2> 761160 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 761160 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@780df358: rootName = solr_45763, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@792fb505
[junit4] 2> 761161 INFO (coreCloseExecutor-10504-thread-1) [n:127.0.0.1:42787_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.infected.shard2.leader tag=SolrCore@1d256408
[junit4] 2> 761161 INFO (coreCloseExecutor-10504-thread-1) [n:127.0.0.1:42787_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 761162 INFO (coreCloseExecutor-10509-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.c.SolrCore [infected_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@5285fe41
[junit4] 2> 761162 INFO (coreCloseExecutor-10509-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.infected.shard1.replica_n1 tag=SolrCore@5285fe41
[junit4] 2> 761162 INFO (coreCloseExecutor-10509-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70fe96d1: rootName = solr_38781, domain = solr.core.infected.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.infected.shard1.replica_n1/com.codahale.metrics.MetricRegistry@7565321d
[junit4] 2> 761166 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 761166 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3f4847cd: rootName = solr_42787, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@47327f13
[junit4] 2> 761166 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 761166 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@69a7fb0: rootName = solr_45763, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5c1f5c47
[junit4] 2> 761169 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 761169 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7a3bdc5d: rootName = solr_45763, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@74fe941e
[junit4] 2> 761169 INFO (jetty-closer-10497-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 761170 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 761170 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b3cd7cb: rootName = solr_42787, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5c1f5c47
[junit4] 2> 761172 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 761172 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@283fdca0: rootName = solr_42787, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@74fe941e
[junit4] 2> 761172 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 761173 INFO (closeThreadPool-10512-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72082977205190662-127.0.0.1:42787_solr-n_0000000000) closing
[junit4] 2> 761173 INFO (OverseerStateUpdate-72082977205190662-127.0.0.1:42787_solr-n_0000000000) [n:127.0.0.1:42787_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42787_solr
[junit4] 2> 761173 INFO (OverseerAutoScalingTriggerThread-72082977205190662-127.0.0.1:42787_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 761175 INFO (coreCloseExecutor-10509-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.infected.shard1.leader tag=SolrCore@5285fe41
[junit4] 2> 761175 INFO (coreCloseExecutor-10509-thread-1) [n:127.0.0.1:38781_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 761175 INFO (zkCallback-10451-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 761175 INFO (zkCallback-10470-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 761178 INFO (closeThreadPool-10512-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72082977205190662-127.0.0.1:42787_solr-n_0000000000) closing
[junit4] 2> 761187 INFO (zkCallback-10470-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 761198 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 761198 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48ff4579: rootName = solr_38781, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3178a8fa
[junit4] 2> 761202 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 761202 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a365983: rootName = solr_38781, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5c1f5c47
[junit4] 2> 761204 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 761204 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4ba555c5: rootName = solr_38781, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@74fe941e
[junit4] 2> 761204 INFO (jetty-closer-10497-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 761275 INFO (jetty-closer-10497-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72082977205190662-127.0.0.1:42787_solr-n_0000000000) closing
[junit4] 2> 761276 INFO (jetty-closer-10497-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7876b8b6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 761277 INFO (jetty-closer-10497-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@561703c{/solr,null,UNAVAILABLE}
[junit4] 2> 761277 INFO (jetty-closer-10497-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 761377 INFO (jetty-closer-10497-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2438eddb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 761377 INFO (jetty-closer-10497-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c62bb66{/solr,null,UNAVAILABLE}
[junit4] 2> 761377 INFO (jetty-closer-10497-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 761477 INFO (jetty-closer-10497-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@628cec98{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 761477 INFO (jetty-closer-10497-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e609b5c{/solr,null,UNAVAILABLE}
[junit4] 2> 761477 INFO (jetty-closer-10497-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 761477 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 761684 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:44793
[junit4] 2> 761684 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44793
[junit4] 2> 761684 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44793
[junit4] 2> 761684 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/collections/infected/terms/shard2
[junit4] 2> 3 /solr/collections/infected/terms/shard1
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2> 2 /solr/collections/infected/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 32 /solr/collections/infected/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/overseer/queue
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001
[junit4] 2> May 21, 2020 7:54:10 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {_version_=BlockTreeOrds(blocksize=128), _root_=BlockTreeOrds(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{}, maxPointsInLeafNode=159, maxMBSortInHeap=5.5747182977683964, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@65bbd83c), locale=pt-BR, timezone=Asia/Karachi
[junit4] 2> NOTE: Linux 5.3.0-51-generic amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=16,threads=1,free=108375808,total=522190848
[junit4] 2> NOTE: All tests run in this JVM: [TestDownShardTolerantSearch, UninvertDocValuesMergePolicyTest, ChaosMonkeyNothingIsSafeTest, TestSimNodeLostTrigger, TaggerTest, DistributedFacetPivotSmallAdvancedTest, LeaderVoteWaitTimeoutTest, TriggerEventQueueTest, MoveReplicaHDFSFailoverTest, HttpSolrCallGetCoreTest, DefaultValueUpdateProcessorTest, TestSuggestSpellingConverter, ZkNodePropsTest, CoreAdminOperationTest, TermVectorComponentTest, TestSolrQueryParser, SaslZkACLProviderTest, DateFieldTest, TestDocBasedVersionConstraints, TestStressCloudBlindAtomicUpdates, JvmMetricsTest, TestBlobHandler, TestQuerySenderListener, AtomicUpdatesTest, ConfigureRecoveryStrategyTest, StatsReloadRaceTest, HdfsThreadLeakTest, InfoHandlerTest, TestLRUStatsCache, CollectionTooManyReplicasTest, IndexSizeTriggerSizeEstimationTest, TestShortCircuitedRequests, PeerSyncReplicationTest, ReturnFieldsTest, TestScoreJoinQPNoScore, DocumentBuilderTest, TestReplicationHandlerBackup, AddReplicaTest, CertAuthPluginTest, TestFieldResource, TestFaceting, TestEmbeddedSolrServerAdminHandler, DistributedQueryElevationComponentTest, DistribCursorPagingTest, TestSolrCLIRunExample, TestDelegationWithHadoopAuth, ReplaceNodeTest, TestCursorMarkWithoutUniqueKey, TestUninvertingReader, TriLevelCompositeIdRoutingTest, HLLUtilTest, TestHdfsCloudBackupRestore, TestDocSet, TestSimDistributedQueue, TestRawResponseWriter, TestUseDocValuesAsStored2, TestCloudPseudoReturnFields, TestSweetSpotSimilarityFactory, OpenExchangeRatesOrgProviderTest, TestHalfAndHalfDocValues, FieldAnalysisRequestHandlerTest, TestHighlightDedupGrouping, TestSolrConfigHandlerCloud, NodeLostTriggerIntegrationTest, TestCustomStream, UtilsToolTest, SolrGraphiteReporterTest, TestBinaryField, TestSolrDeletionPolicy1, CloudExitableDirectoryReaderTest, SimpleMLTQParserTest, OverseerSolrResponseTest, TestPayloadCheckQParserPlugin, PropertiesRequestHandlerTest, AutoScalingHandlerTest, ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, DeleteInactiveReplicaTest, DeleteReplicaTest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, NestedShardedAtomicUpdateTest, OverseerCollectionConfigSetProcessorTest, PackageManagerCLITest, SystemCollectionCompatTest, TestCloudConsistency, TestOnReconnectListenerSupport, TestPullReplica, TestQueryingOnDownCollection]
[junit4] Completed [505/913 (1!)] on J1 in 2.71s, 1 test, 1 failure <<< FAILURES!
[...truncated 53769 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/26735/consoleText
[repro] Revision: 0728ef06e98cee5a278b8d75054d0f0c9d33a5ac
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestQueryingOnDownCollection -Dtests.method=testQueryToDownCollectionShouldFailFast -Dtests.seed=714F0A74DDCC17A6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt-BR -Dtests.timezone=Asia/Karachi -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestQueryingOnDownCollection
[repro] ant compile-test
[...truncated 3134 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestQueryingOnDownCollection" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=714F0A74DDCC17A6 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt-BR -Dtests.timezone=Asia/Karachi -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 42 lines...]
[junit4] Suite: org.apache.solr.cloud.TestQueryingOnDownCollection
[junit4] 2> 1739 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1744 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1810 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/data-dir-1-001
[junit4] 2> 1984 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2022 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.e.j.u.log Logging initialized @2043ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2027 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2046 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001/tempDir-001
[junit4] 2> 2054 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2071 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2071 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2170 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 41263
[junit4] 2> 2172 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer waitFor
[...truncated too long message...]
8388 INFO (jetty-closer-93-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 8388 INFO (jetty-closer-93-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 8388 INFO (jetty-closer-93-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5dddbea3: rootName = solr_35623, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@6e7cd453
[junit4] 2> 8388 INFO (jetty-closer-93-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@27e2c0cb: rootName = solr_39723, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@6e7cd453
[junit4] 2> 8388 INFO (jetty-closer-93-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 8388 INFO (jetty-closer-93-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 8390 INFO (zkCallback-44-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [2])
[junit4] 2> 8390 INFO (zkCallback-42-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [2])
[junit4] 2> 8390 INFO (zkCallback-44-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [2])
[junit4] 2> 8390 INFO (zkCallback-44-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [2])
[junit4] 2> 8390 INFO (zkCallback-42-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [2])
[junit4] 2> 8390 INFO (zkCallback-42-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/infected/state.json] for collection [infected] has occurred - updating... (live nodes size: [2])
[junit4] 2> 8395 INFO (closeThreadPool-109-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72083051768446983-127.0.0.1:35623_solr-n_0000000000) closing
[junit4] 2> 8396 INFO (OverseerAutoScalingTriggerThread-72083051768446983-127.0.0.1:35623_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 8396 INFO (OverseerStateUpdate-72083051768446983-127.0.0.1:35623_solr-n_0000000000) [n:127.0.0.1:35623_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35623_solr
[junit4] 2> 8397 INFO (closeThreadPool-109-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72083051768446983-127.0.0.1:35623_solr-n_0000000000) closing
[junit4] 2> 8467 INFO (jetty-closer-93-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@738c3f48{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 8468 INFO (jetty-closer-93-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5c6b9d98{/solr,null,UNAVAILABLE}
[junit4] 2> 8468 INFO (jetty-closer-93-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8570 INFO (jetty-closer-93-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3c22b280{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 8570 INFO (jetty-closer-93-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@71d023f3{/solr,null,UNAVAILABLE}
[junit4] 2> 8571 INFO (jetty-closer-93-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8665 INFO (jetty-closer-93-thread-3) [ ] o.a.s.c.Overseer Overseer (id=72083051768446983-127.0.0.1:35623_solr-n_0000000000) closing
[junit4] 2> 8671 INFO (jetty-closer-93-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@80f3a24{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 8671 INFO (jetty-closer-93-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4e98bbc8{/solr,null,UNAVAILABLE}
[junit4] 2> 8671 INFO (jetty-closer-93-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8672 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 8876 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/collections/infected/terms/shard2
[junit4] 2> 3 /solr/collections/infected/terms/shard1
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2> 2 /solr/collections/infected/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/collections/infected/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/overseer/queue
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 8880 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41263
[junit4] 2> 8880 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41263
[junit4] 2> 8880 INFO (SUITE-TestQueryingOnDownCollection-seed#[714F0A74DDCC17A6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41263
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestQueryingOnDownCollection_714F0A74DDCC17A6-001
[junit4] 2> May 21, 2020 8:13:13 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {_version_=BlockTreeOrds(blocksize=128), _root_=BlockTreeOrds(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{}, maxPointsInLeafNode=159, maxMBSortInHeap=5.5747182977683964, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@13f6f61c), locale=pt-BR, timezone=Asia/Karachi
[junit4] 2> NOTE: Linux 5.3.0-51-generic amd64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=16,threads=2,free=352288912,total=501219328
[junit4] 2> NOTE: All tests run in this JVM: [TestQueryingOnDownCollection]
[junit4] Completed [3/5 (1!)] on J2 in 8.47s, 1 test, 1 failure <<< FAILURES!
[...truncated 21 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1521: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1048: There were test failures: 5 suites, 5 tests, 1 failure [seed: 714F0A74DDCC17A6]
Total time: 11 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 1/5 failed: org.apache.solr.cloud.TestQueryingOnDownCollection
[repro] Exiting with code 256
+ 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
[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=0728ef06e98cee5a278b8d75054d0f0c9d33a5ac, workspace=/home/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #26734
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