Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/1633/
Java: 64bit/jdk-13.0.1 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
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([359CF312E9DAE19:D084E69B89ACC765]:0)
at
org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2722)
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:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:830)
Build Log:
[...truncated 13699 lines...]
[junit4] Suite: org.apache.solr.cloud.TestQueryingOnDownCollection
[junit4] 2> 283266 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 283267 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/data-dir-24-001
[junit4] 2> 283267 WARN
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 283267 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 283268 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 283269 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001
[junit4] 2> 283269 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 283269 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 283269 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 283369 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:46159
[junit4] 2> 283369 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:46159
[junit4] 2> 283369 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46159
[junit4] 2> 283369 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 46159
[junit4] 2> 283371 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283372 INFO (zkConnectionManagerCallback-1663-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283372 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283374 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283376 INFO (zkConnectionManagerCallback-1665-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283376 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283387 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283388 INFO (zkConnectionManagerCallback-1667-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283388 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283496 WARN (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 283496 WARN (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 283496 WARN (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 283496 WARN (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 283496 WARN (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 283496 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 283496 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 283496 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 283496 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 283497 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 13.0.1+9
[junit4] 2> 283497 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 13.0.1+9
[junit4] 2> 283497 WARN (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 283497 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 283497 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 283497 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git:
363d5f2df3a8a28de40604320230664b9c793c16; jvm 13.0.1+9
[junit4] 2> 283500 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 283500 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 283500 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 283500 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 283500 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 283500 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 283501 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 283501 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 283501 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 283501 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@23087a3a{/solr,null,AVAILABLE}
[junit4] 2> 283501 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6017c6c2{/solr,null,AVAILABLE}
[junit4] 2> 283501 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2da57d9e{/solr,null,AVAILABLE}
[junit4] 2> 283502 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@46ec2f99{ssl,[ssl, alpn,
http/1.1, h2]}{127.0.0.1:၄၂၁၇၁}
[junit4] 2> 283502 INFO (jetty-launcher-1668-thread-3) [ ]
o.e.j.s.Server Started @၂၈၃၅၂၂ms
[junit4] 2> 283502 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=42171}
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1c121488{ssl,[ssl, alpn,
http/1.1, h2]}{127.0.0.1:၃၃၆၉၁}
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.e.j.s.Server Started @၂၈၃၅၂၂ms
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33691}
[junit4] 2> 283503 ERROR (jetty-launcher-1668-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 283503 ERROR (jetty-launcher-1668-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.5.0
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.5.0
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-12-17T05:12:28.470339Z
[junit4] 2> 283503 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-12-17T05:12:28.470338Z
[junit4] 2> 283504 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@54be9133{ssl,[ssl, alpn,
http/1.1, h2]}{127.0.0.1:၃၇၅၅၅}
[junit4] 2> 283504 INFO (jetty-launcher-1668-thread-2) [ ]
o.e.j.s.Server Started @၂၈၃၅၂၄ms
[junit4] 2> 283504 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37555}
[junit4] 2> 283505 ERROR (jetty-launcher-1668-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 283505 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 283505 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.5.0
[junit4] 2> 283505 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 283505 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 283505 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2019-12-17T05:12:28.472161Z
[junit4] 2> 283507 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283507 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283508 INFO (zkConnectionManagerCallback-1671-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283508 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283508 INFO (zkConnectionManagerCallback-1672-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283508 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283508 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283508 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 283509 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 283511 INFO (zkConnectionManagerCallback-1674-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283511 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283512 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 283774 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 283775 WARN (jetty-launcher-1668-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@d27dba2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283775 WARN (jetty-launcher-1668-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@d27dba2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283778 WARN (jetty-launcher-1668-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@432737a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283778 WARN (jetty-launcher-1668-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@432737a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283779 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46159/solr
[junit4] 2> 283783 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283787 INFO (zkConnectionManagerCallback-1682-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283787 INFO (jetty-launcher-1668-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283833 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 283835 WARN (jetty-launcher-1668-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@478a92a3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283835 WARN (jetty-launcher-1668-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@478a92a3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283838 WARN (jetty-launcher-1668-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@7fdb04ea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283838 WARN (jetty-launcher-1668-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@7fdb04ea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283838 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46159/solr
[junit4] 2> 283842 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283855 INFO (zkConnectionManagerCallback-1689-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283858 INFO (jetty-launcher-1668-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283900 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 283901 WARN (jetty-launcher-1668-thread-3) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@498e71fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283901 WARN (jetty-launcher-1668-thread-3) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@498e71fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283907 WARN (jetty-launcher-1668-thread-3) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@436909c0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283907 WARN (jetty-launcher-1668-thread-3) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@436909c0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 283908 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46159/solr
[junit4] 2> 283909 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 283910 INFO (zkConnectionManagerCallback-1695-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283910 INFO (jetty-launcher-1668-thread-3) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 283915 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 283915 INFO (zkConnectionManagerCallback-1697-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 283915 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 283976 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:33691_solr
[junit4] 2> 283977 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.Overseer Overseer
(id=72197523227869193-127.0.0.1:33691_solr-n_0000000000) starting
[junit4] 2> 283982 INFO
(OverseerStateUpdate-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[n:127.0.0.1:33691_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:33691_solr
[junit4] 2> 283987 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33691_solr
[junit4] 2> 283989 INFO (zkCallback-1696-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 283989 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 283989 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 284003 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 284015 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 284016 INFO (zkConnectionManagerCallback-1703-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 284016 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 284020 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 284023 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:42171_solr as DOWN
[junit4] 2> 284024 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 284024 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:42171_solr
[junit4] 2> 284025 INFO (zkCallback-1696-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 284025 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33691.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284025 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 284025 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 284026 INFO (zkCallback-1702-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 284032 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33691.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284032 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33691.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284033 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 284034 INFO (jetty-launcher-1668-thread-1)
[n:127.0.0.1:33691_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node1/.
[junit4] 2> 284034 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 284066 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42171.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284075 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42171.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284075 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_42171.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284076 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 284078 INFO (jetty-launcher-1668-thread-3)
[n:127.0.0.1:42171_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node3/.
[junit4] 2> 284115 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 284116 INFO (zkConnectionManagerCallback-1709-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 284116 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 284119 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 284121 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:37555_solr as DOWN
[junit4] 2> 284121 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 284121 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37555_solr
[junit4] 2> 284122 INFO (zkCallback-1702-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 284122 INFO (zkCallback-1696-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 284123 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.CoreContainer Initializing authorization
plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 284123 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: solr.BasicAuthPlugin
[junit4] 2> 284128 INFO (zkCallback-1708-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 284139 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 284155 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37555.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284163 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37555.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284164 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37555.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 284165 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 284166 INFO (jetty-launcher-1668-thread-2)
[n:127.0.0.1:37555_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node2/.
[junit4] 2> 284193 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 284194 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 284195 INFO (zkConnectionManagerCallback-1718-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 284195 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 284195 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 284196 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46159/solr ready
[junit4] 2> 284213 INFO
(TEST-TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast-seed#[359CF312E9DAE19])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testQueryToDownCollectionShouldFailFast
[junit4] 2> 284233 INFO (qtp1407773374-4975) [n:127.0.0.1:33691_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> 284251 INFO
(OverseerThreadFactory-2131-thread-1-processing-n:127.0.0.1:33691_solr)
[n:127.0.0.1:33691_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
infected
[junit4] 2> 284383 INFO
(OverseerStateUpdate-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[n:127.0.0.1:33691_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":"https://127.0.0.1:33691/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 284385 INFO
(OverseerStateUpdate-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[n:127.0.0.1:33691_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_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:37555/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 284692 INFO (qtp457467408-5048) [n:127.0.0.1:37555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 284693 INFO (qtp457467408-4971) [ ]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:37555_solr /
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAzR05tOzm4Bxd5NzBb+vu9Ls33u5LJdUlDl7TOgr7ahFqfrFtsDdqn9AzsAtu3KpU+5ihLqhv45i47zFrjCqdkn9ExVlX2Qmdc+X8/MpTTbWfFZtqvznr02CofER7CJOdGw74yb04PEn75pO1b+DHkRBfhD7faJNEbyt6800CkFg3PgXSswNd+z2c8keQhtqOy6YxDiONvyrcN6t/hZU1vWDBFDpQerMqyarluoJwHoGIzJKod7WMuOfgyO57rdTb117Fr0KQXJDb64htJCQhUdysty9J5m2HLNyZ9sDiNdcP46WSV/M8wf0pDAFaeUukYhxd9xucT2n7MTrmm5pWXwIDAQAB
[junit4] 2> 284697 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 284700 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
x:infected_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=infected_shard2_replica_n3&action=CREATE&numShards=2&collection=infected&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 284701 INFO (qtp1407773374-4970) [ ]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:37555_solr /
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAzR05tOzm4Bxd5NzBb+vu9Ls33u5LJdUlDl7TOgr7ahFqfrFtsDdqn9AzsAtu3KpU+5ihLqhv45i47zFrjCqdkn9ExVlX2Qmdc+X8/MpTTbWfFZtqvznr02CofER7CJOdGw74yb04PEn75pO1b+DHkRBfhD7faJNEbyt6800CkFg3PgXSswNd+z2c8keQhtqOy6YxDiONvyrcN6t/hZU1vWDBFDpQerMqyarluoJwHoGIzJKod7WMuOfgyO57rdTb117Fr0KQXJDb64htJCQhUdysty9J5m2HLNyZ9sDiNdcP46WSV/M8wf0pDAFaeUukYhxd9xucT2n7MTrmm5pWXwIDAQAB
[junit4] 2> 284702 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
x:infected_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&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> 284703 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
x:infected_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 285727 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 285736 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.s.IndexSchema [infected_shard1_replica_n1] Schema name=minimal
[junit4] 2> 285737 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.0
[junit4] 2> 285738 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 285738 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.CoreContainer Creating SolrCore 'infected_shard1_replica_n1' using
configuration from collection infected, trusted=true
[junit4] 2> 285738 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33691.solr.core.infected.shard1.replica_n1' (registry
'solr.core.infected.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 285739 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.SolrCore [[infected_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node1/infected_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node1/./infected_shard1_replica_n1/data/]
[junit4] 2> 285747 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.s.IndexSchema [infected_shard2_replica_n3] Schema name=minimal
[junit4] 2> 285748 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 285748 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.CoreContainer Creating SolrCore 'infected_shard2_replica_n3' using
configuration from collection infected, trusted=true
[junit4] 2> 285748 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_37555.solr.core.infected.shard2.replica_n3' (registry
'solr.core.infected.shard2.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5ce01ddf
[junit4] 2> 285748 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.SolrCore [[infected_shard2_replica_n3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node2/infected_shard2_replica_n3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001/tempDir-001/node2/./infected_shard2_replica_n3/data/]
[junit4] 2> 285833 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 285833 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 285835 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 285835 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 285836 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@65536f44[infected_shard1_replica_n1] main]
[junit4] 2> 285839 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 285840 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 285840 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 285840 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1653142507547000832
[junit4] 2> 285843 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/infected/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 285843 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/infected/leaders/shard1
[junit4] 2> 285845 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 285845 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 285845 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:33691/solr/infected_shard1_replica_n1/
[junit4] 2> 285845 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 285846 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.SyncStrategy https://127.0.0.1:33691/solr/infected_shard1_replica_n1/
has no replicas
[junit4] 2> 285846 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 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/72197523227869193-core_node2-n_0000000000
[junit4] 2> 285847 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:33691/solr/infected_shard1_replica_n1/ shard1
[junit4] 2> 285855 INFO
(searcherExecutor-2144-thread-1-processing-n:127.0.0.1:33691_solr
x:infected_shard1_replica_n1 c:infected s:shard1 r:core_node2)
[n:127.0.0.1:33691_solr c:infected s:shard1 r:core_node2
x:infected_shard1_replica_n1 ] o.a.s.c.SolrCore [infected_shard1_replica_n1]
Registered new searcher Searcher@65536f44[infected_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 285948 INFO (zkCallback-1696-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> 285948 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 285950 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 285950 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 285953 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 285953 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 285955 INFO (zkCallback-1696-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> 285958 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@49306f03[infected_shard2_replica_n3] main]
[junit4] 2> 285959 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 285959 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 285960 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 285960 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1653142507672829952
[junit4] 2> 285961 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=infected_shard1_replica_n1&action=CREATE&numShards=2&collection=infected&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1259
[junit4] 2> 285982 INFO
(searcherExecutor-2145-thread-1-processing-n:127.0.0.1:37555_solr
x:infected_shard2_replica_n3 c:infected s:shard2 r:core_node4)
[n:127.0.0.1:37555_solr c:infected s:shard2 r:core_node4
x:infected_shard2_replica_n3 ] o.a.s.c.SolrCore [infected_shard2_replica_n3]
Registered new searcher Searcher@49306f03[infected_shard2_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 285984 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/infected/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 285984 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContextBase make sure parent is created
/collections/infected/leaders/shard2
[junit4] 2> 285986 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 285986 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 285986 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:37555/solr/infected_shard2_replica_n3/
[junit4] 2> 285986 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 285987 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.SyncStrategy https://127.0.0.1:37555/solr/infected_shard2_replica_n3/
has no replicas
[junit4] 2> 285987 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/infected/leaders/shard2/leader after winning as
/collections/infected/leader_elect/shard2/election/72197523227869195-core_node4-n_0000000000
[junit4] 2> 285996 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:37555/solr/infected_shard2_replica_n3/ shard2
[junit4] 2> 286098 INFO (zkCallback-1696-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> 286098 INFO (zkCallback-1696-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> 286098 INFO (zkCallback-1708-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> 286098 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 286101 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=infected_shard2_replica_n3&action=CREATE&numShards=2&collection=infected&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1401
[junit4] 2> 286112 INFO (zkCallback-1696-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> 286127 INFO (qtp1407773374-4975) [n:127.0.0.1:33691_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> 286212 INFO (zkCallback-1708-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> 286212 INFO (zkCallback-1708-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> 286212 INFO (zkCallback-1696-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> 286212 INFO (zkCallback-1696-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> 286212 INFO (zkCallback-1696-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> 286213 INFO (qtp1407773374-4975) [n:127.0.0.1:33691_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=1980
[junit4] 2> 286262 INFO
(OverseerCollectionConfigSetProcessor-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[n:127.0.0.1:33691_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 286273 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/infected/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 286273 INFO (qtp457467408-4971) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.p.LogUpdateProcessorFactory [infected_shard2_replica_n3] webapp=/solr
path=/update params={_stateVer_=infected:5&wt=javabin&version=2}{add=[2
(1653142507994742784), 3 (1653142507995791360), 5 (1653142507995791361), 6
(1653142507995791362), 7 (1653142507995791363), 9 (1653142507996839936), 17
(1653142507996839937), 18 (1653142507996839938), 19 (1653142507996839939), 21
(1653142507996839940), ... (44 adds)]} 0 7
[junit4] 2> 286287 INFO (qtp1407773374-4973) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/infected/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 286287 INFO (qtp1407773374-4973) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.p.LogUpdateProcessorFactory [infected_shard1_replica_n1] webapp=/solr
path=/update params={_stateVer_=infected:5&wt=javabin&version=2}{add=[0
(1653142507977965568), 1 (1653142508003131392), 4 (1653142508003131393), 8
(1653142508003131394), 10 (1653142508003131395), 11 (1653142508004179968), 12
(1653142508004179969), 13 (1653142508004179970), 14 (1653142508004179971), 15
(1653142508004179972), ... (56 adds)]} 0 44
[junit4] 2> 286383 INFO (qtp1407773374-4975) [n:127.0.0.1:33691_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 286385 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr ]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:33691_solr /
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAkKHOSI2h+ZR2pJaPP05V9oPggrV1M8HCfmiUFQRX9XhMoJZ0S9IQDy4ZLn77/69j3qLszJk614/Hlw2mRledXrIDobEfEJopiODZpYbxSGKpwYRJM2u6Esg4glwePBkUZLgigeQREPwjFnGpmub+QXPKdOwf5KqbOY/bgxmJRDpu/KtRDBtNWVUrIjyDaw7LK3STMgKuExIWJt2uUH0FqjZzwfUf3pbGfPaIRlpdz5UULtSM8GOscyqHkXKl8GsDCbrezV/RxtFQWoG7Kiu22XdUOzeyvuRLPSoMeYTkHCShaRE8Z5Y9QtoaWNTU1wwJ8fI1sABCG3uQZMp+AZ7RZwIDAQAB
[junit4] 2> 286386 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1653142508119523328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 286396 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@51596a30
commitCommandVersion:1653142508119523328
[junit4] 2> 286439 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4b3b6042[infected_shard2_replica_n3] main]
[junit4] 2> 286441 INFO
(searcherExecutor-2145-thread-1-processing-n:127.0.0.1:37555_solr
x:infected_shard2_replica_n3 c:infected s:shard2 r:core_node4)
[n:127.0.0.1:37555_solr c:infected s:shard2 r:core_node4
x:infected_shard2_replica_n3 ] o.a.s.c.SolrCore [infected_shard2_replica_n3]
Registered new searcher Searcher@4b3b6042[infected_shard2_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.0):C44:[diagnostics={os=Linux,
java.vendor=AdoptOpenJDK, java.version=13.0.1, java.vm.version=13.0.1+9,
lucene.version=8.5.0, os.arch=amd64, java.runtime.version=13.0.1+9,
source=flush, os.version=5.0.0-36-generic,
timestamp=1576559551396}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 286441 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 286441 INFO (qtp457467408-4969) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.u.p.LogUpdateProcessorFactory [infected_shard2_replica_n3] webapp=/solr
path=/update
params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:33691/solr/infected_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 56
[junit4] 2> 286442 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1653142508178243584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 286442 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@793aa17f
commitCommandVersion:1653142508178243584
[junit4] 2> 286449 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@44dd5e5a[infected_shard1_replica_n1] main]
[junit4] 2> 286451 INFO
(searcherExecutor-2144-thread-1-processing-n:127.0.0.1:33691_solr
x:infected_shard1_replica_n1 c:infected s:shard1 r:core_node2)
[n:127.0.0.1:33691_solr c:infected s:shard1 r:core_node2
x:infected_shard1_replica_n1 ] o.a.s.c.SolrCore [infected_shard1_replica_n1]
Registered new searcher Searcher@44dd5e5a[infected_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.0):C56:[diagnostics={os=Linux,
java.vendor=AdoptOpenJDK, java.version=13.0.1, java.vm.version=13.0.1+9,
lucene.version=8.5.0, os.arch=amd64, java.runtime.version=13.0.1+9,
source=flush, os.version=5.0.0-36-generic,
timestamp=1576559551414}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 286452 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 286452 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.u.p.LogUpdateProcessorFactory [infected_shard1_replica_n1] webapp=/solr
path=/update
params={_stateVer_=infected:5&commit=true&wt=javabin&version=2}{commit=} 0 154
[junit4] 2> 286455 INFO (zkCallback-1708-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> 286457 INFO (zkCallback-1708-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> 286458 INFO (zkCallback-1696-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> 286458 INFO (zkCallback-1696-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> 286459 INFO (zkCallback-1696-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> 286551 INFO (qtp457467408-4966) [n:127.0.0.1:37555_solr
c:infected s:shard2 r:core_node4 x:infected_shard2_replica_n3 ]
o.a.s.c.S.Request [infected_shard2_replica_n3] webapp=/solr path=/select
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&shard.url=https://127.0.0.1:37555/solr/infected_shard2_replica_n3/&rows=0&version=2&q=*:*&omitHeader=false&NOW=1576559551427&isShard=true&wt=javabin}
hits=44 status=0 QTime=1
[junit4] 2> 286567 INFO (qtp1407773374-4975) [n:127.0.0.1:33691_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key
params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 286568 INFO (qtp1407773374-4973) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.s.PKIAuthenticationPlugin New Key obtained from node:
127.0.0.1:33691_solr /
MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAkKHOSI2h+ZR2pJaPP05V9oPggrV1M8HCfmiUFQRX9XhMoJZ0S9IQDy4ZLn77/69j3qLszJk614/Hlw2mRledXrIDobEfEJopiODZpYbxSGKpwYRJM2u6Esg4glwePBkUZLgigeQREPwjFnGpmub+QXPKdOwf5KqbOY/bgxmJRDpu/KtRDBtNWVUrIjyDaw7LK3STMgKuExIWJt2uUH0FqjZzwfUf3pbGfPaIRlpdz5UULtSM8GOscyqHkXKl8GsDCbrezV/RxtFQWoG7Kiu22XdUOzeyvuRLPSoMeYTkHCShaRE8Z5Y9QtoaWNTU1wwJ8fI1sABCG3uQZMp+AZ7RZwIDAQAB
[junit4] 2> 286568 INFO (qtp1407773374-4973) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 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=https://127.0.0.1:33691/solr/infected_shard1_replica_n1/&rows=0&version=2&q=*:*&omitHeader=false&NOW=1576559551427&isShard=true&wt=javabin}
hits=56 status=0 QTime=0
[junit4] 2> 286572 INFO (qtp1407773374-4970) [n:127.0.0.1:33691_solr
c:infected s:shard1 r:core_node2 x:infected_shard1_replica_n1 ]
o.a.s.c.S.Request [infected_shard1_replica_n1] webapp=/solr path=/select
params={q=*:*&rows=0&wt=javabin&version=2} hits=100 status=0 QTime=112
[junit4] 2> 286573 INFO
(TEST-TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast-seed#[359CF312E9DAE19])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testQueryToDownCollectionShouldFailFast
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestQueryingOnDownCollection
-Dtests.method=testQueryToDownCollectionShouldFailFast
-Dtests.seed=359CF312E9DAE19 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=my-MM -Dtests.timezone=Asia/Hebron -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 2.38s J1 |
TestQueryingOnDownCollection.testQueryToDownCollectionShouldFailFast <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Request
should fail after trying all replica nodes once
[junit4] > at
__randomizedtesting.SeedInfo.seed([359CF312E9DAE19:D084E69B89ACC765]:0)
[junit4] > at
org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2722)
[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:830)
[junit4] 2> 286687 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1858166068
[junit4] 2> 286688 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:33691_solr
[junit4] 2> 286689 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2134494745
[junit4] 2> 286689 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:42171_solr
[junit4] 2> 286689 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 286689 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:42171_solr as DOWN
[junit4] 2> 286697 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1678383010
[junit4] 2> 286697 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:37555_solr
[junit4] 2> 286699 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 286699 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:33691_solr as DOWN
[junit4] 2> 286699 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 286699 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.c.ZkController Publish node=127.0.0.1:37555_solr as DOWN
[junit4] 2> 286735 INFO (coreCloseExecutor-2155-thread-1)
[n:127.0.0.1:33691_solr c:infected s:shard1 r:core_node2
x:infected_shard1_replica_n1 ] o.a.s.c.SolrCore [infected_shard1_replica_n1]
CLOSING SolrCore org.apache.solr.core.SolrCore@75cb0fc7
[junit4] 2> 286735 INFO (coreCloseExecutor-2155-thread-1)
[n:127.0.0.1:33691_solr c:infected s:shard1 r:core_node2
x:infected_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.infected.shard1.replica_n1,
tag=SolrCore@75cb0fc7
[junit4] 2> 286735 INFO (coreCloseExecutor-2155-thread-1)
[n:127.0.0.1:33691_solr c:infected s:shard1 r:core_node2
x:infected_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@417730c8: rootName =
solr_33691, 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@29607f28
[junit4] 2> 286744 INFO (coreCloseExecutor-2154-thread-1)
[n:127.0.0.1:37555_solr c:infected s:shard2 r:core_node4
x:infected_shard2_replica_n3 ] o.a.s.c.SolrCore [infected_shard2_replica_n3]
CLOSING SolrCore org.apache.solr.core.SolrCore@791e11ce
[junit4] 2> 286744 INFO (coreCloseExecutor-2154-thread-1)
[n:127.0.0.1:37555_solr c:infected s:shard2 r:core_node4
x:infected_shard2_replica_n3 ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.infected.shard2.replica_n3,
tag=SolrCore@791e11ce
[junit4] 2> 286744 INFO (coreCloseExecutor-2154-thread-1)
[n:127.0.0.1:37555_solr c:infected s:shard2 r:core_node4
x:infected_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@217aec98: rootName =
solr_37555, domain = solr.core.infected.shard2.replica_n3, service url = null,
agent id = null] for registry solr.core.infected.shard2.replica_n3 /
com.codahale.metrics.MetricRegistry@22e3bdb4
[junit4] 2> 286751 INFO (coreCloseExecutor-2155-thread-1)
[n:127.0.0.1:33691_solr c:infected s:shard1 r:core_node2
x:infected_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.infected.shard1.leader,
tag=SolrCore@75cb0fc7
[junit4] 2> 286783 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 286783 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7c6e70ba: rootName =
solr_42171, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@d5a1941
[junit4] 2> 286799 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 286799 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ddc3ca4: rootName =
solr_42171, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@37a19b9e
[junit4] 2> 286801 ERROR
(OverseerStateUpdate-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[n:127.0.0.1:33691_solr ] o.a.s.c.Overseer Exception in Overseer main queue
loop
[junit4] 2> =>
org.apache.zookeeper.KeeperException$BadVersionException: KeeperErrorCode =
BadVersion for /collections/infected/state.json
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:122)
[junit4] 2> org.apache.zookeeper.KeeperException$BadVersionException:
KeeperErrorCode = BadVersion for /collections/infected/state.json
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:122)
~[zookeeper-3.5.5.jar:3.5.5]
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
~[zookeeper-3.5.5.jar:3.5.5]
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:2384)
~[zookeeper-3.5.5.jar:3.5.5]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.lambda$setData$6(SolrZkClient.java:350)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71)
~[java/:?]
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:350)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:199)
~[java/:?]
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:259)
~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:830) [?:?]
[junit4] 2> 286803 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 286803 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@21c60d0e: rootName =
solr_42171, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@75f877ce
[junit4] 2> 286803 INFO (jetty-closer-1721-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 286803 INFO (coreCloseExecutor-2154-thread-1)
[n:127.0.0.1:37555_solr c:infected s:shard2 r:core_node4
x:infected_shard2_replica_n3 ] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.infected.shard2.leader,
tag=SolrCore@791e11ce
[junit4] 2> 286806 INFO (zkCallback-1708-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> 286806 INFO (zkCallback-1708-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> 286806 INFO (zkCallback-1696-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> 286806 INFO (zkCallback-1696-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> 286808 INFO (zkCallback-1708-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> 286808 INFO (zkCallback-1708-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> 286808 INFO (zkCallback-1696-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> 286808 INFO (zkCallback-1696-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> 286808 INFO (zkCallback-1696-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> 286827 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 286827 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2e002ee8: rootName =
solr_33691, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@2c9b8c90
[junit4] 2> 286832 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 286832 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@49478d42: rootName =
solr_33691, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@37a19b9e
[junit4] 2> 286834 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 286834 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@4063f2f3: rootName =
solr_33691, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@75f877ce
[junit4] 2> 286834 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 286876 INFO (zkCallback-1696-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 286876 INFO (zkCallback-1708-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 286939 INFO (closeThreadPool-1732-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72197523227869193-127.0.0.1:33691_solr-n_0000000000) closing
[junit4] 2> 286939 INFO
(OverseerStateUpdate-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[n:127.0.0.1:33691_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33691_solr
[junit4] 2> 286943 INFO
(OverseerAutoScalingTriggerThread-72197523227869193-127.0.0.1:33691_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 286967 INFO (zkCallback-1708-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 286991 INFO (closeThreadPool-1732-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72197523227869193-127.0.0.1:33691_solr-n_0000000000) closing
[junit4] 2> 286991 INFO (jetty-closer-1721-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@46ec2f99{ssl,[ssl, alpn,
http/1.1, h2]}{127.0.0.1:၀}
[junit4] 2> 286991 INFO (jetty-closer-1721-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2da57d9e{/solr,null,UNAVAILABLE}
[junit4] 2> 286991 INFO (jetty-closer-1721-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 287001 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 287001 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@7b83d3e2: rootName =
solr_37555, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@425986f3
[junit4] 2> 287007 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 287007 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@a97d7ef: rootName =
solr_37555, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@37a19b9e
[junit4] 2> 287013 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 287013 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5f67305a: rootName =
solr_37555, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@75f877ce
[junit4] 2> 287013 INFO (jetty-closer-1721-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 287081 INFO (jetty-closer-1721-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72197523227869193-127.0.0.1:33691_solr-n_0000000000) closing
[junit4] 2> 287083 INFO (jetty-closer-1721-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@1c121488{ssl,[ssl, alpn,
http/1.1, h2]}{127.0.0.1:၀}
[junit4] 2> 287083 INFO (jetty-closer-1721-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6017c6c2{/solr,null,UNAVAILABLE}
[junit4] 2> 287083 INFO (jetty-closer-1721-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 287145 INFO (jetty-closer-1721-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@54be9133{ssl,[ssl, alpn,
http/1.1, h2]}{127.0.0.1:၀}
[junit4] 2> 287145 INFO (jetty-closer-1721-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@23087a3a{/solr,null,UNAVAILABLE}
[junit4] 2> 287145 INFO (jetty-closer-1721-thread-3) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 287146 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 287351 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> 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> 33 /solr/collections/infected/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 2
/solr/overseer_elect/election/72197523227869193-127.0.0.1:33691_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 21 /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> 287353 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:46159
[junit4] 2> 287353 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46159
[junit4] 2> 287353 INFO
(SUITE-TestQueryingOnDownCollection-seed#[359CF312E9DAE19]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1 46159
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.TestQueryingOnDownCollection_359CF312E9DAE19-001
[junit4] 2> Dec 17, 2019 5:12:32 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84):
{_root_=BlockTreeOrds(blocksize=128), id=Lucene84},
docValues:{_version_=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=2047,
maxMBSortInHeap=7.724387739802671,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@325e0f00),
locale=my-MM, timezone=Asia/Hebron
[junit4] 2> NOTE: Linux 5.0.0-36-generic amd64/AdoptOpenJDK 13.0.1
(64-bit)/cpus=8,threads=1,free=217090712,total=393216000
[junit4] 2> NOTE: All tests run in this JVM: [RestartWhileUpdatingTest,
TestAnalyzeInfixSuggestions, HLLUtilTest, TestShardHandlerFactory,
SolrJmxReporterTest, TestPolicyCloud, TestGraphMLResponseWriter,
CdcrBootstrapTest, TestPayloadCheckQParserPlugin, ComputePlanActionTest,
TestMaxScoreQueryParser, TestLockTree, BasicAuthStandaloneTest,
TestLegacyNumericUtils, TestFieldCache, BinaryUpdateRequestHandlerTest,
ConfigureRecoveryStrategyTest, DistributedFacetPivotSmallAdvancedTest,
SpatialHeatmapFacetsTest, TestManagedSynonymGraphFilterFactory,
TestCopyFieldCollectionResource, TestSolrCLIRunExample,
PathHierarchyTokenizerFactoryTest, HdfsUnloadDistributedZkTest,
DistanceFunctionTest, TestQueryingOnDownCollection]
[junit4] Completed [104/889 (1!)] on J1 in 5.04s, 1 test, 1 failure <<<
FAILURES!
[...truncated 47564 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/1633/consoleText
[repro] Revision: 10ea628bb6f4104ba14485d4b3dd2ee7f369a90e
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=TestQueryingOnDownCollection
-Dtests.method=testQueryToDownCollectionShouldFailFast
-Dtests.seed=359CF312E9DAE19 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=my-MM -Dtests.timezone=Asia/Hebron -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 3605 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.TestQueryingOnDownCollection" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=359CF312E9DAE19
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=my-MM
-Dtests.timezone=Asia/Hebron -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 75 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.TestQueryingOnDownCollection
[repro] Exiting with code 0
[...truncated 43 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
