You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/05/28 14:00:18 UTC
[JENKINS] Lucene-Solr-NightlyTests-master - Build # 2206 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/2206/
1 tests failed.
FAILED: org.apache.solr.search.stats.TestDistribIDF.testMultiCollectionQuery
Error Message:
expected:<[2]> but was:<[1]>
Stack Trace:
org.junit.ComparisonFailure: expected:<[2]> but was:<[1]>
at __randomizedtesting.SeedInfo.seed([43B03567B877166E:52C3F25664011312]:0)
at org.junit.Assert.assertEquals(Assert.java:115)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.search.stats.TestDistribIDF.testMultiCollectionQuery(TestDistribIDF.java:179)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:834)
Build Log:
[...truncated 13980 lines...]
[junit4] Suite: org.apache.solr.search.stats.TestDistribIDF
[junit4] 2> 2262000 INFO (SUITE-TestDistribIDF-seed#[43B03567B877166E]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2262001 INFO (SUITE-TestDistribIDF-seed#[43B03567B877166E]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/data-dir-90-001
[junit4] 2> 2262001 WARN (SUITE-TestDistribIDF-seed#[43B03567B877166E]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 2262001 INFO (SUITE-TestDistribIDF-seed#[43B03567B877166E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2262002 INFO (SUITE-TestDistribIDF-seed#[43B03567B877166E]-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> 2262002 INFO (SUITE-TestDistribIDF-seed#[43B03567B877166E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2262014 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultiCollectionQuery
[junit4] 2> 2262014 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001
[junit4] 2> 2262015 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2262015 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2262015 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2262115 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer start zk server on port: 44078
[junit4] 2> 2262115 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:44078
[junit4] 2> 2262115 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44078
[junit4] 2> 2262115 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44078
[junit4] 2> 2262117 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262120 INFO (zkConnectionManagerCallback-18505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262120 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262122 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262124 INFO (zkConnectionManagerCallback-18507-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262124 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262125 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262126 INFO (zkConnectionManagerCallback-18509-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262126 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 2262231 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
[junit4] 2> 2262233 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2262233 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2262233 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6db4932d{/solr,null,AVAILABLE}
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9f34a12{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35948}
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.e.j.s.Server Started @2262272ms
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35948}
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2262234 ERROR (jetty-launcher-18510-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75447bf8{/solr,null,AVAILABLE}
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@489d59f6{/solr,null,AVAILABLE}
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 2262234 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-28T11:37:03.303963Z
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@55611f60{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34608}
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.e.j.s.Server Started @2262273ms
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@771b0105{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:41319}
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.e.j.s.Server Started @2262273ms
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34608}
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=41319}
[junit4] 2> 2262235 ERROR (jetty-launcher-18510-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2262235 ERROR (jetty-launcher-18510-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-28T11:37:03.304674Z
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr
[junit4] 2> 2262235 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-28T11:37:03.304716Z
[junit4] 2> 2262236 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262236 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262236 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262237 INFO (zkConnectionManagerCallback-18512-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262237 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262237 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2262237 INFO (zkConnectionManagerCallback-18516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262237 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262237 INFO (zkConnectionManagerCallback-18514-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262237 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262238 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2262238 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2262369 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2262370 WARN (jetty-launcher-18510-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@224ccb22[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262370 WARN (jetty-launcher-18510-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@224ccb22[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262373 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2262374 WARN (jetty-launcher-18510-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@15cb73fd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262374 WARN (jetty-launcher-18510-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@15cb73fd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262374 WARN (jetty-launcher-18510-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@392dc23c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262374 WARN (jetty-launcher-18510-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@392dc23c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262376 INFO (jetty-launcher-18510-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44078/solr
[junit4] 2> 2262377 WARN (jetty-launcher-18510-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5b70df7f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262377 WARN (jetty-launcher-18510-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5b70df7f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262378 INFO (jetty-launcher-18510-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44078/solr
[junit4] 2> 2262379 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262380 INFO (zkConnectionManagerCallback-18538-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262380 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262389 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262390 INFO (zkConnectionManagerCallback-18535-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262390 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262422 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34608_solr
[junit4] 2> 2262423 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.Overseer Overseer (id=72872950919790599-127.0.0.1:34608_solr-n_0000000000) starting
[junit4] 2> 2262445 INFO (OverseerStateUpdate-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34608_solr
[junit4] 2> 2262462 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34608_solr
[junit4] 2> 2262464 INFO (zkCallback-18534-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2262562 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2262562 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2262562 WARN (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2262563 WARN (jetty-launcher-18510-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@10c9e3d0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262563 WARN (jetty-launcher-18510-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@10c9e3d0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262565 WARN (jetty-launcher-18510-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@479ab01d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262565 WARN (jetty-launcher-18510-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@479ab01d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2262567 INFO (jetty-launcher-18510-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44078/solr
[junit4] 2> 2262580 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2262581 INFO (zkConnectionManagerCallback-18558-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2262581 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2262608 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2262622 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:41319_solr as DOWN
[junit4] 2> 2262623 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 2262623 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41319_solr
[junit4] 2> 2262649 INFO (zkCallback-18557-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2262649 INFO (zkCallback-18534-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2262651 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2262652 WARN (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2262717 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2262749 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2262753 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35948_solr as DOWN
[junit4] 2> 2262763 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 2262763 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35948_solr
[junit4] 2> 2262764 INFO (zkCallback-18534-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2262764 INFO (zkCallback-18557-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2262764 INFO (zkCallback-18537-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2262766 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2262766 WARN (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2262781 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2262879 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2262910 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34608.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2262936 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41319.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2262949 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34608.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2262949 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34608.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2262951 INFO (jetty-launcher-18510-thread-1) [n:127.0.0.1:34608_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node1/.
[junit4] 2> 2262962 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41319.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2262962 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41319.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2262964 INFO (jetty-launcher-18510-thread-2) [n:127.0.0.1:41319_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node2/.
[junit4] 2> 2262985 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35948.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2263016 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35948.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2263016 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35948.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2263018 INFO (jetty-launcher-18510-thread-3) [n:127.0.0.1:35948_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node3/.
[junit4] 2> 2263033 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 2263034 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2263035 INFO (zkConnectionManagerCallback-18582-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2263035 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2263037 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2263038 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44078/solr ready
[junit4] 2> 2263039 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2263041 INFO (zkConnectionManagerCallback-18584-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2263041 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2263863 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2263864 INFO (zkConnectionManagerCallback-18586-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2263864 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2263973 INFO (qtp120890500-35144) [n:127.0.0.1:35948_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&maxShardsPerNode=1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2263977 INFO (OverseerThreadFactory-18547-thread-1-processing-n:127.0.0.1:34608_solr) [n:127.0.0.1:34608_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2264085 INFO (qtp120890500-35142) [n:127.0.0.1:35948_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=1
[junit4] 2> 2264087 INFO (qtp2044518478-35152) [n:127.0.0.1:41319_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> 2264089 INFO (qtp1537669732-35151) [n:127.0.0.1:34608_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 2264090 INFO (qtp1537669732-35151) [n:127.0.0.1:34608_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> 2264099 INFO (qtp120890500-35146) [n:127.0.0.1:35948_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> 2264101 INFO (qtp2044518478-35154) [n:127.0.0.1:41319_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> 2264102 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_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> 2264107 INFO (OverseerStateUpdate-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34608/solr",
[junit4] 2> "node_name":"127.0.0.1:34608_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2264110 INFO (OverseerStateUpdate-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41319/solr",
[junit4] 2> "node_name":"127.0.0.1:41319_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2264313 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2264313 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2264364 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2264372 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2264434 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2264438 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2265022 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2265041 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2265067 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 2265068 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34608.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2265080 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node1/./collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/]
[junit4] 2> 2265088 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from configset conf1, trusted=true
[junit4] 2> 2265101 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_41319.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2265101 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node2/./collection1_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node2/./collection1_shard2_replica_n2/data/]
[junit4] 2> 2265121 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=65.1923828125, floorSegmentMB=0.3896484375, forceMergeDeletesPctAllowed=22.605841282343874, segmentsPerTier=12.0, maxCFSSegmentSizeMB=2.0888671875, noCFSRatio=1.0, deletesPctAllowed=30.357604679853296
[junit4] 2> 2265121 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=65.1923828125, floorSegmentMB=0.3896484375, forceMergeDeletesPctAllowed=22.605841282343874, segmentsPerTier=12.0, maxCFSSegmentSizeMB=2.0888671875, noCFSRatio=1.0, deletesPctAllowed=30.357604679853296
[junit4] 2> 2265562 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2265562 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2265643 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2265643 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2265644 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2265644 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2265645 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=31.0673828125, floorSegmentMB=0.7021484375, forceMergeDeletesPctAllowed=23.06135550509767, segmentsPerTier=36.0, maxCFSSegmentSizeMB=1.76953125, noCFSRatio=1.0, deletesPctAllowed=20.6095245452231
[junit4] 2> 2265647 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2265647 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2265648 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2265648 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=8, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=31.0673828125, floorSegmentMB=0.7021484375, forceMergeDeletesPctAllowed=23.06135550509767, segmentsPerTier=36.0, maxCFSSegmentSizeMB=1.76953125, noCFSRatio=1.0, deletesPctAllowed=20.6095245452231
[junit4] 2> 2265671 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2265683 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 2265684 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 2265759 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2265759 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667934010031996928
[junit4] 2> 2265783 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2265784 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2265784 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2265784 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2265785 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2265785 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2265786 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2265786 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667934010060308480
[junit4] 2> 2265786 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2265788 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2265796 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 2265796 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 2265799 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2265799 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2265799 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2265805 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2265805 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 2265808 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2265808 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2265808 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34608/solr/collection1_shard1_replica_n1/
[junit4] 2> 2265809 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2265809 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2265809 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41319/solr/collection1_shard2_replica_n2/
[junit4] 2> 2265809 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2265809 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2265809 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:34608/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 2265809 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72872950919790599-core_node3-n_0000000000
[junit4] 2> 2265809 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:41319/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 2265809 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72872950919790600-core_node4-n_0000000000
[junit4] 2> 2265823 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2265823 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2265824 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34608/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 2265824 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:41319/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 2265824 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2265824 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2265825 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2265826 INFO (searcherExecutor-18588-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'collection1'
[junit4] 2> 2265826 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2265827 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2265845 INFO (searcherExecutor-18590-thread-1-processing-n:127.0.0.1:41319_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'collection1'
[junit4] 2> 2265926 INFO (zkCallback-18557-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2265927 INFO (zkCallback-18534-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2265927 INFO (zkCallback-18557-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2265927 INFO (zkCallback-18534-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2265927 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2265928 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2265930 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1617
[junit4] 2> 2265930 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1617
[junit4] 2> 2265932 INFO (qtp120890500-35144) [n:127.0.0.1:35948_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> 2265979 INFO (OverseerCollectionConfigSetProcessor-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_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> 2266030 INFO (zkCallback-18534-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266030 INFO (zkCallback-18534-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266030 INFO (zkCallback-18534-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266030 INFO (zkCallback-18557-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266030 INFO (zkCallback-18557-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266030 INFO (zkCallback-18537-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266031 INFO (zkCallback-18557-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2266031 INFO (qtp120890500-35144) [n:127.0.0.1:35948_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&maxShardsPerNode=1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2058
[junit4] 2> 2266031 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: collection1
[junit4] 2> 2266033 INFO (qtp120890500-35142) [n:127.0.0.1:35948_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf2&maxShardsPerNode=1&name=collection1_local&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2266053 INFO (OverseerThreadFactory-18547-thread-2-processing-n:127.0.0.1:34608_solr) [n:127.0.0.1:34608_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1_local
[junit4] 2> 2266269 INFO (qtp120890500-35146) [n:127.0.0.1:35948_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=1
[junit4] 2> 2266283 INFO (qtp2044518478-35152) [n:127.0.0.1:41319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2266284 INFO (qtp2044518478-35154) [n:127.0.0.1:41319_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> 2266285 INFO (qtp1537669732-35151) [n:127.0.0.1:34608_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2266286 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_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> 2266288 INFO (qtp120890500-35144) [n:127.0.0.1:35948_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> 2266289 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2266299 INFO (qtp2044518478-35152) [n:127.0.0.1:41319_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=9
[junit4] 2> 2266300 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2266301 INFO (qtp1537669732-35151) [n:127.0.0.1:34608_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> 2266366 INFO (OverseerStateUpdate-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_local",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_local_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:35948/solr",
[junit4] 2> "node_name":"127.0.0.1:35948_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2266377 INFO (OverseerStateUpdate-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1_local",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_local_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34608/solr",
[junit4] 2> "node_name":"127.0.0.1:34608_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2266581 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr x:collection1_local_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf2&newCollection=true&name=collection1_local_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_local&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2266581 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr x:collection1_local_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf2&newCollection=true&name=collection1_local_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_local&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2266603 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2266627 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2266654 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2266673 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2266673 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_local_shard2_replica_n2' using configuration from configset conf2, trusted=true
[junit4] 2> 2266673 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34608.solr.core.collection1_local.shard2.replica_n2' (registry 'solr.core.collection1_local.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2266673 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_local_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node1/./collection1_local_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node1/./collection1_local_shard2_replica_n2/data/]
[junit4] 2> 2266714 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2266733 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2266733 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_local_shard1_replica_n1' using configuration from configset conf2, trusted=true
[junit4] 2> 2266733 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35948.solr.core.collection1_local.shard1.replica_n1' (registry 'solr.core.collection1_local.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@56aa9796
[junit4] 2> 2266733 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_local_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node3/./collection1_local_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001/tempDir-001/node3/./collection1_local_shard1_replica_n1/data/]
[junit4] 2> 2267267 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2267267 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2267287 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 2267288 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 2267288 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2267291 INFO (searcherExecutor-18600-thread-1-processing-n:127.0.0.1:34608_solr x:collection1_local_shard2_replica_n2 c:collection1_local s:shard2 r:core_node4) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_local_shard2_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'collection1_local'
[junit4] 2> 2267297 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_local/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 2267297 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1_local/leaders/shard2
[junit4] 2> 2267306 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2267306 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2267309 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf2
[junit4] 2> 2267309 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf2
[junit4] 2> 2267310 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2267313 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2267313 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2267313 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34608/solr/collection1_local_shard2_replica_n2/
[junit4] 2> 2267313 ERROR (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
[junit4] 2> 2267313 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2267313 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1_local/leaders/shard2/leader after winning as /collections/collection1_local/leader_elect/shard2/election/72872950919790599-core_node4-n_0000000000
[junit4] 2> 2267324 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34608/solr/collection1_local_shard2_replica_n2/ shard2
[junit4] 2> 2267324 INFO (searcherExecutor-18602-thread-1-processing-n:127.0.0.1:35948_solr x:collection1_local_shard1_replica_n1 c:collection1_local s:shard1 r:core_node3) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_local_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'collection1_local'
[junit4] 2> 2267344 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1_local/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 2267344 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1_local/leaders/shard1
[junit4] 2> 2267361 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2267361 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2267361 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35948/solr/collection1_local_shard1_replica_n1/
[junit4] 2> 2267361 ERROR (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
[junit4] 2> 2267361 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 2267361 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1_local/leaders/shard1/leader after winning as /collections/collection1_local/leader_elect/shard1/election/72872950919790598-core_node3-n_0000000000
[junit4] 2> 2267376 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:35948/solr/collection1_local_shard1_replica_n1/ shard1
[junit4] 2> 2267497 INFO (zkCallback-18537-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267497 INFO (zkCallback-18534-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267497 INFO (zkCallback-18534-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267497 INFO (zkCallback-18537-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267498 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr c:collection1_local s:shard2 r:core_node4 x:collection1_local_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2267498 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr c:collection1_local s:shard1 r:core_node3 x:collection1_local_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2267501 INFO (qtp1537669732-35153) [n:127.0.0.1:34608_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf2&newCollection=true&name=collection1_local_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1_local&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=919
[junit4] 2> 2267513 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf2&newCollection=true&name=collection1_local_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1_local&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=932
[junit4] 2> 2267515 INFO (qtp120890500-35142) [n:127.0.0.1:35948_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> 2267602 INFO (zkCallback-18534-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267602 INFO (zkCallback-18534-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267602 INFO (zkCallback-18534-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267602 INFO (zkCallback-18537-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267603 INFO (zkCallback-18537-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267603 INFO (zkCallback-18537-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1_local/state.json] for collection [collection1_local] has occurred - updating... (live nodes size: [3])
[junit4] 2> 2267603 INFO (qtp120890500-35142) [n:127.0.0.1:35948_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf2&maxShardsPerNode=1&name=collection1_local&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1570
[junit4] 2> 2267603 INFO (TEST-TestDistribIDF.testMultiCollectionQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: collection1_local
[junit4] 2> 2267605 INFO (qtp120890500-35144) [n:127.0.0.1:35948_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&maxShardsPerNode=1&name=collection2&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2267609 INFO (OverseerThreadFactory-18547-thread-3-processing-n:127.0.0.1:34608_solr) [n:127.0.0.1:34608_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2
[junit4] 2> 2267609 INFO (OverseerCollectionConfigSetProcessor-72872950919790599-127.0.0.1:34608_solr-n_0000000000) [n:127.0.0.1:34608_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2267844 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1_local.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2267845 INFO (qtp120890500-35142) [n:127.0.0.1:35948_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> 2267846 INFO (qtp2044518478-35154) [n:127.0.0.1:41319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2267847 INFO (qtp2044518478-35150) [n:127.0.0.1:41319_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=1
[junit4] 2> 2267848 INFO (qtp1537669732-35148) [n:127.0.0.1:34608_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.collection1_local.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2267850 INFO (qtp1537669732-35151) [n:127.0.0.1:34608_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> 2267851 INFO (qtp120890500-35146) [n:127.0.0.1:35948_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1_local.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2267853 INFO (qtp120890500-35142) [n:127.0.0.1:35948_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> 2267853 INFO (qtp2044518478-35152) [n:127.0.0.1:41319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2267855 INFO (qtp2044518478-35154) [n:127.0.0.1:41319_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINE
[...truncated too long message...]
read-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 2280145 INFO (closeThreadPool-18782-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72872951585701895-127.0.0.1:35648_solr-n_0000000000) closing
[junit4] 2> 2280145 INFO (OverseerStateUpdate-72872951585701895-127.0.0.1:35648_solr-n_0000000000) [n:127.0.0.1:35648_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35648_solr
[junit4] 2> 2280146 INFO (jetty-closer-18766-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2280146 INFO (jetty-closer-18766-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33dcd9b8: rootName = solr_34446, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5ac3633
[junit4] 2> 2280146 INFO (OverseerAutoScalingTriggerThread-72872951585701895-127.0.0.1:35648_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2280146 INFO (jetty-closer-18766-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 2280146 INFO (jetty-closer-18766-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2280146 INFO (jetty-closer-18766-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e2ead57: rootName = solr_37049, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5ac3633
[junit4] 2> 2280146 INFO (jetty-closer-18766-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 2280151 INFO (zkCallback-18685-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2280151 INFO (zkCallback-18703-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2280173 INFO (closeThreadPool-18782-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72872951585701895-127.0.0.1:35648_solr-n_0000000000) closing
[junit4] 2> 2280250 INFO (jetty-closer-18766-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@54362be5{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2280250 INFO (jetty-closer-18766-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6ade08db{/solr,null,UNAVAILABLE}
[junit4] 2> 2280250 INFO (jetty-closer-18766-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2280349 INFO (jetty-closer-18766-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72872951585701895-127.0.0.1:35648_solr-n_0000000000) closing
[junit4] 2> 2280350 INFO (jetty-closer-18766-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2f08d1c9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2280350 INFO (jetty-closer-18766-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@673afad3{/solr,null,UNAVAILABLE}
[junit4] 2> 2280350 INFO (jetty-closer-18766-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2280450 INFO (jetty-closer-18766-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@272da7b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2280451 INFO (jetty-closer-18766-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@ab7652e{/solr,null,UNAVAILABLE}
[junit4] 2> 2280451 INFO (jetty-closer-18766-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2280451 INFO (TEST-TestDistribIDF.testSimpleQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2280655 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/clusterprops.json
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/collections/onecollection_local/collectionprops.json
[junit4] 2> 3 /solr/configs/conf2
[junit4] 2> 3 /solr/collections/onecollection/terms/b
[junit4] 2> 3 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/onecollection/terms/a
[junit4] 2> 3 /solr/collections/onecollection/collectionprops.json
[junit4] 2> 2 /solr/collections/onecollection/terms/c
[junit4] 2> 2 /solr/collections/onecollection_local/terms/c
[junit4] 2> 2 /solr/collections/onecollection_local/terms/b
[junit4] 2> 2 /solr/collections/onecollection_local/terms/a
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 37 /solr/collections/onecollection/state.json
[junit4] 2> 37 /solr/collections/onecollection_local/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 33 /solr/overseer/queue
[junit4] 2> 12 /solr/live_nodes
[junit4] 2> 12 /solr/collections
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 2280660 INFO (TEST-TestDistribIDF.testSimpleQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:46840
[junit4] 2> 2280660 INFO (TEST-TestDistribIDF.testSimpleQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:46840
[junit4] 2> 2280660 INFO (TEST-TestDistribIDF.testSimpleQuery-seed#[43B03567B877166E]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 46840
[junit4] 2> 2280661 INFO (TEST-TestDistribIDF.testSimpleQuery-seed#[43B03567B877166E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSimpleQuery
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J1/temp/solr.search.stats.TestDistribIDF_43B03567B877166E-001
[junit4] 2> May 28, 2020 11:37:21 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(Lucene86): {multiDefault=PostingsFormat(name=Direct), cat=PostingsFormat(name=Direct), _root_=PostingsFormat(name=MockRandom), id=FST50, _route_=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Lucene80), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=799, maxMBSortInHeap=6.541293957423731, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3ab2053c), locale=hr, timezone=America/Kentucky/Louisville
[junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=1,free=300293816,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [GraphQueryTest, SuggesterTSTTest, SolrRequestParserTest, TestInfoStreamLogging, TestDeleteCollectionOnDownNodes, TestIndexSearcher, JvmMetricsTest, TestPayloadCheckQParserPlugin, TestLegacyTerms, LeaderFailoverAfterPartitionTest, CollectionsAPISolrJTest, CoreAdminOperationTest, TestHighlightDedupGrouping, TestOnReconnectListenerSupport, QueryResultKeyTest, TestShardHandlerFactory, TestLRUStatsCacheCloud, DistributedQueryElevationComponentTest, TestExactStatsCacheCloud, ResourceLoaderTest, TestEmbeddedSolrServerAdminHandler, ExternalFileFieldSortTest, SolrSlf4jReporterTest, CollectionTooManyReplicasTest, MoveReplicaHDFSFailoverTest, TestFieldResource, TestSimTriggerIntegration, BasicAuthIntegrationTest, PreAnalyzedFieldTest, TestTermsQParserPlugin, TestReload, TestCloudSearcherWarming, BJQFilterAccessibleTest, SpellCheckCollatorWithCollapseTest, ZkDynamicConfigTest, DistribCursorPagingTest, ZkNodePropsTest, TestTrackingShardHandlerFactory, TestInPlaceUpdatesRequiredField, TestTlogReplayVsRecovery, TestRestManager, ConnectionManagerTest, DistributedFacetExistsSmallTest, TestBlobHandler, TestSimNodeLostTrigger, PeerSyncReplicationTest, QueryEqualityTest, UtilsToolTest, ConcurrentCreateCollectionTest, SolrRrdBackendFactoryTest, ReindexCollectionTest, TestConfigSetsAPIExclusivity, SuggestComponentContextFilterQueryTest, ShowFileRequestHandlerTest, ExitableDirectoryReaderTest, HLLUtilTest, UUIDUpdateProcessorFallbackTest, TestScoreJoinQPNoScore, ZkCLITest, RollingRestartTest, CertAuthPluginTest, TestCodecSupport, AutoscalingHistoryHandlerTest, HdfsRecoveryZkTest, OrderedExecutorTest, DateFieldTest, TestFieldCacheSortRandom, TestFieldSortValues, DocumentBuilderTest, TestLocalStatsCacheCloud, DistributedQueueTest, TestShortCircuitedRequests, TestLegacyFieldCache, TestRequestForwarding, HdfsCollectionsAPIDistributedZkTest, TestExplainDocTransformer, TestSolrConfigHandlerCloud, TestDocBasedVersionConstraints, TestRawResponseWriter, TestHdfsUpdateLog, PeerSyncWithBufferUpdatesTest, AssignTest, TestMacroExpander, TestDelegationWithHadoopAuth, ChaosMonkeyNothingIsSafeTest, XmlUpdateRequestHandlerTest, SortSpecParsingTest, AutoScalingHandlerTest, TestBinaryField, TestLazyCores, TestJettySolrRunner, TestStressCloudBlindAtomicUpdates, ChaosMonkeySafeLeaderWithPullReplicasTest, TestZkAclsWithHadoopAuth, PeerSyncWithLeaderTest, UpdateLogTest, BaseCdcrDistributedZkTest, TestDistribIDF]
[junit4] Completed [265/913 (1!)] on J1 in 19.68s, 2 tests, 1 failure <<< FAILURES!
[...truncated 54596 lines...]