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 2021/08/06 16:52:01 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.9 - Build # 351 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.9/351/
2 tests failed.
FAILED: org.apache.solr.cloud.LeaderTragicEventTest.testLeaderFailsOver
Error Message:
Error from server at http://127.0.0.1:37521/solr: Underlying core creation failed while creating collection: testLeaderFailsOver
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:37521/solr: Underlying core creation failed while creating collection: testLeaderFailsOver
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.cloud.LeaderTragicEventTest.testLeaderFailsOver(LeaderTragicEventTest.java:80)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.metrics.reporters.solr.SolrCloudReportersTest.testExplicitConfiguration
Error Message:
leaderRegistries expected:<2> but was:<1>
Stack Trace:
java.lang.AssertionError: leaderRegistries expected:<2> but was:<1>
at __randomizedtesting.SeedInfo.seed([6A06DC04BDA794FB:2ECAFCFFD39D4291]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:647)
at org.apache.solr.metrics.reporters.solr.SolrCloudReportersTest.testExplicitConfiguration(SolrCloudReportersTest.java:161)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15128 lines...]
[junit4] Suite: org.apache.solr.cloud.LeaderTragicEventTest
[junit4] 2> 1339859 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1339859 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1339859 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/data-dir-152-001
[junit4] 2> 1339859 WARN (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=22 numCloses=22
[junit4] 2> 1339859 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1339861 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1339861 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001
[junit4] 2> 1339861 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1339861 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1339862 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1339862 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1339962 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 36055
[junit4] 2> 1339962 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:36055
[junit4] 2> 1339962 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36055
[junit4] 2> 1339962 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36055
[junit4] 2> 1339963 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1339965 INFO (zkConnectionManagerCallback-13270-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1339965 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1339967 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1339968 INFO (zkConnectionManagerCallback-13272-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1339968 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1339968 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1339969 INFO (zkConnectionManagerCallback-13274-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1339969 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1340071 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1340071 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1340071 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1340072 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1340072 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10
[junit4] 2> 1340072 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1340081 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9e19ff2{/solr,null,AVAILABLE}
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7bd98ee4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39379}
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c5810e4{/solr,null,AVAILABLE}
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.e.j.s.Server Started @1340115ms
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36055/solr, hostPort=39379}
[junit4] 2> 1340082 ERROR (jetty-launcher-13275-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@299a6f3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37521}
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.e.j.s.Server Started @1340116ms
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.1
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36055/solr, hostPort=37521}
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-08-06T16:15:16.185Z
[junit4] 2> 1340082 ERROR (jetty-launcher-13275-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.1
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr
[junit4] 2> 1340082 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-08-06T16:15:16.185Z
[junit4] 2> 1340083 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1340091 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1340091 INFO (zkConnectionManagerCallback-13277-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1340091 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1340091 INFO (zkConnectionManagerCallback-13279-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1340091 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1340091 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1340092 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1340103 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1340103 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1340985 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1340986 WARN (jetty-launcher-13275-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@370bb7ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1340986 WARN (jetty-launcher-13275-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@370bb7ca[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1340988 WARN (jetty-launcher-13275-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@61efe34b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1340988 WARN (jetty-launcher-13275-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@61efe34b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1340989 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36055/solr
[junit4] 2> 1340989 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1340990 INFO (zkConnectionManagerCallback-13293-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1340990 INFO (jetty-launcher-13275-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1341092 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1341093 INFO (zkConnectionManagerCallback-13295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1341093 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1341111 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1341112 WARN (jetty-launcher-13275-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60a2899a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1341112 WARN (jetty-launcher-13275-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60a2899a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1341114 WARN (jetty-launcher-13275-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@c93d6a3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1341114 WARN (jetty-launcher-13275-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@c93d6a3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1341115 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36055/solr
[junit4] 2> 1341115 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1341116 INFO (zkConnectionManagerCallback-13305-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1341116 INFO (jetty-launcher-13275-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1341149 WARN (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1341153 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37521_solr
[junit4] 2> 1341153 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.Overseer Overseer (id=72187085860044806-127.0.0.1:37521_solr-n_0000000000) starting
[junit4] 2> 1341158 INFO (OverseerStateUpdate-72187085860044806-127.0.0.1:37521_solr-n_0000000000) [n:127.0.0.1:37521_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37521_solr
[junit4] 2> 1341158 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37521_solr
[junit4] 2> 1341159 INFO (OverseerStateUpdate-72187085860044806-127.0.0.1:37521_solr-n_0000000000) [n:127.0.0.1:37521_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1341161 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1341161 WARN (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_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> 1341176 INFO (jetty-launcher-13275-thread-2) [n:127.0.0.1:37521_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node2
[junit4] 2> 1341218 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1341219 INFO (zkConnectionManagerCallback-13315-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1341219 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1341222 WARN (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1341223 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1341225 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39379_solr as DOWN
[junit4] 2> 1341226 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1341226 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39379_solr
[junit4] 2> 1341227 INFO (zkCallback-13294-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1341227 INFO (zkCallback-13314-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1341228 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1341228 WARN (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_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> 1341237 INFO (jetty-launcher-13275-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node1
[junit4] 2> 1341243 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1341244 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1341245 INFO (zkConnectionManagerCallback-13326-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1341245 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1341246 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1341247 INFO (SUITE-LeaderTragicEventTest-seed#[6A06DC04BDA794FB]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36055/solr ready
[junit4] 2> 1341266 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOtherReplicasAreNotActive
[junit4] 2> 1341269 INFO (OverseerThreadFactory-13310-thread-1-processing-n:127.0.0.1:37521_solr) [n:127.0.0.1:37521_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testOtherReplicasAreNotActive
[junit4] 2> 1341375 INFO (OverseerStateUpdate-72187085860044806-127.0.0.1:37521_solr-n_0000000000) [n:127.0.0.1:37521_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37521_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37521/solr",
[junit4] 2> "collection":"testOtherReplicasAreNotActive",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1341377 INFO (OverseerStateUpdate-72187085860044806-127.0.0.1:37521_solr-n_0000000000) [n:127.0.0.1:37521_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:39379_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39379/solr",
[junit4] 2> "collection":"testOtherReplicasAreNotActive",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1341580 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n1&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1341580 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1341581 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n2&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1341592 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 1341593 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 1341594 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1341594 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1341594 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testOtherReplicasAreNotActive_shard1_replica_n2' using configuration from configset config, trusted=true
[junit4] 2> 1341594 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [[testOtherReplicasAreNotActive_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2/data/]
[junit4] 2> 1341596 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1341596 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1341596 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testOtherReplicasAreNotActive_shard1_replica_n1' using configuration from configset config, trusted=true
[junit4] 2> 1341596 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SolrCore [[testOtherReplicasAreNotActive_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node2/testOtherReplicasAreNotActive_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node2/testOtherReplicasAreNotActive_shard1_replica_n1/data/]
[junit4] 2> 1341633 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1341633 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1341633 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1341633 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1341634 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1341634 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1341634 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1341634 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1341635 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1341635 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/config
[junit4] 2> 1341635 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1341635 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 1341635 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1341635 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1341635 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1707361192103641088
[junit4] 2> 1341635 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1707361192103641088
[junit4] 2> 1341637 INFO (searcherExecutor-13328-thread-1-processing-n:127.0.0.1:39379_solr x:testOtherReplicasAreNotActive_shard1_replica_n2 c:testOtherReplicasAreNotActive s:shard1 r:core_node4) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1341637 INFO (searcherExecutor-13330-thread-1-processing-n:127.0.0.1:37521_solr x:testOtherReplicasAreNotActive_shard1_replica_n1 c:testOtherReplicasAreNotActive s:shard1 r:core_node3) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1341641 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying
[junit4] 2> 1341641 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testOtherReplicasAreNotActive/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1341641 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testOtherReplicasAreNotActive/leaders/shard1
[junit4] 2> 1341642 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testOtherReplicasAreNotActive/terms/shard1 to Terms{values={core_node3=0, core_node4=0}, version=1}
[junit4] 2> 1341642 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testOtherReplicasAreNotActive/leaders/shard1
[junit4] 2> 1341645 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1341645 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1341645 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37521/solr/testOtherReplicasAreNotActive_shard1_replica_n1/
[junit4] 2> 1341646 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testOtherReplicasAreNotActive_shard1_replica_n1 url=http://127.0.0.1:37521/solr START replicas=[http://127.0.0.1:39379/solr/testOtherReplicasAreNotActive_shard1_replica_n2/] nUpdates=100
[junit4] 2> 1341646 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testOtherReplicasAreNotActive_shard1_replica_n1 url=http://127.0.0.1:37521/solr DONE. We have no versions. sync failed.
[junit4] 2> 1341647 INFO (qtp1964928567-20303) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.S.Request [testOtherReplicasAreNotActive_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1341647 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1341647 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_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> 1341647 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testOtherReplicasAreNotActive/leaders/shard1/leader after winning as /collections/testOtherReplicasAreNotActive/leader_elect/shard1/election/72187085860044806-core_node3-n_0000000000
[junit4] 2> 1341649 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37521/solr/testOtherReplicasAreNotActive_shard1_replica_n1/ shard1
[junit4] 2> 1341751 INFO (zkCallback-13294-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1341751 INFO (zkCallback-13294-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1341751 INFO (zkCallback-13294-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1341751 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1341755 ERROR (qtp207505672-20307) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Collection testOtherReplicasAreNotActive is not lazy or watched!
[junit4] 2> 1341757 INFO (qtp207505672-20307) [n:127.0.0.1:37521_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n1&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=176
[junit4] 2> 1341853 INFO (zkCallback-13294-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1341853 INFO (zkCallback-13294-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1341853 INFO (zkCallback-13294-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1341853 INFO (zkCallback-13294-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342649 INFO (qtp1964928567-20300) [n:127.0.0.1:39379_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=testOtherReplicasAreNotActive_shard1_replica_n2&action=CREATE&numShards=1&collection=testOtherReplicasAreNotActive&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1068
[junit4] 2> 1342651 INFO (qtp207505672-20305) [n:127.0.0.1:37521_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> 1342749 INFO (zkCallback-13314-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342749 INFO (zkCallback-13294-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342749 INFO (zkCallback-13294-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342749 INFO (zkCallback-13314-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342749 INFO (zkCallback-13294-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342749 INFO (zkCallback-13294-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1342750 INFO (qtp207505672-20305) [n:127.0.0.1:37521_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=config&name=testOtherReplicasAreNotActive&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1482
[junit4] 2> 1342750 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testOtherReplicasAreNotActive
[junit4] 2> 1342753 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.LeaderTragicEventTest Stop jetty node : http://127.0.0.1:39379/solr state:DocCollection(testOtherReplicasAreNotActive//collections/testOtherReplicasAreNotActive/state.json/4 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37521_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37521/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:39379_solr",
[junit4] 2> "base_url":"http://127.0.0.1:39379/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}},
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0"}
[junit4] 2> 1342753 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=822149813
[junit4] 2> 1342753 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39379_solr
[junit4] 2> 1342754 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1342754 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:39379_solr as DOWN
[junit4] 2> 1342755 INFO (coreCloseExecutor-13342-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.c.SolrCore [testOtherReplicasAreNotActive_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@52d564cf
[junit4] 2> 1342755 INFO (coreCloseExecutor-13342-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testOtherReplicasAreNotActive.shard1.replica_n2 tag=SolrCore@52d564cf
[junit4] 2> 1342755 INFO (coreCloseExecutor-13342-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testOtherReplicasAreNotActive.shard1.leader tag=SolrCore@52d564cf
[junit4] 2> 1342756 INFO (coreCloseExecutor-13342-thread-1) [n:127.0.0.1:39379_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1342756 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1342756 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1342756 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1342756 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1342760 INFO (zkCallback-13325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1342761 INFO (zkCallback-13294-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1342856 INFO (zkCallback-13294-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1342856 INFO (zkCallback-13294-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1342856 INFO (zkCallback-13294-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1342856 INFO (zkCallback-13294-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testOtherReplicasAreNotActive/state.json] for collection [testOtherReplicasAreNotActive] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1342861 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7bd98ee4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1342862 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9e19ff2{/solr,null,STOPPED}
[junit4] 2> 1342862 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1342864 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 39379
[junit4] 2> 1342864 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.SolrCloudTestCase waitForState (testOtherReplicasAreNotActive): Timeout waiting for replica get down
[junit4] 2> 1342867 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.LeaderTragicEventTest Will crash leader : core_node3:{
[junit4] 2> "core":"testOtherReplicasAreNotActive_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37521_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37521/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 1342869 ERROR (qtp207505672-20301) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Error closing IndexWriter
[junit4] 2> => java.lang.IllegalStateException: this writer hit an unrecoverable error; cannot flush
[junit4] 2> at org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3894)
[junit4] 2> java.lang.IllegalStateException: this writer hit an unrecoverable error; cannot flush
[junit4] 2> at org.apache.lucene.index.IndexWriter.doFlush(IndexWriter.java:3894) ~[java/:?]
[junit4] 2> at org.apache.lucene.index.IndexWriter.flush(IndexWriter.java:3886) ~[java/:?]
[junit4] 2> at org.apache.lucene.index.IndexWriter.shutdown(IndexWriter.java:1235) ~[java/:?]
[junit4] 2> at org.apache.lucene.index.IndexWriter.close(IndexWriter.java:1278) ~[java/:?]
[junit4] 2> at org.apache.solr.update.SolrIndexWriter.close(SolrIndexWriter.java:298) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.changeWriter(DefaultSolrCoreState.java:209) ~[java/:?]
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.newIndexWriter(DefaultSolrCoreState.java:233) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.checkTragicException(CoreContainer.java:2215) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:229) ~[java/:?]
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) ~[jetty-rewrite-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:713) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388) ~[jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633) [jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380) [jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [jetty-server-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:882) [jetty-util-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1036) [jetty-util-9.4.41.v20210516.jar:9.4.41.v20210516]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> Caused by: java.lang.Exception: injected tragedy
[junit4] 2> at org.apache.solr.util.TestInjection.injectLeaderTragedy(TestInjection.java:364) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:210) ~[java/:?]
[junit4] 2> ... 36 more
[junit4] 2> 1342871 INFO (qtp207505672-20301) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1342871 ERROR (qtp207505672-20301) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Random tragedy fail
[junit4] 2> at org.apache.solr.util.TestInjection.injectLeaderTragedy(TestInjection.java:374)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:210)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:713)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:882)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1036)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1342871 INFO (qtp207505672-20301) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.c.S.Request [testOtherReplicasAreNotActive_shard1_replica_n1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2} status=500 QTime=2
[junit4] 2> 1342871 ERROR (qtp207505672-20301) [n:127.0.0.1:37521_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node3 x:testOtherReplicasAreNotActive_shard1_replica_n1 ] o.a.s.s.HttpSolrCall org.apache.solr.common.SolrException: Random tragedy fail
[junit4] 2> at org.apache.solr.util.TestInjection.injectLeaderTragedy(TestInjection.java:374)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:210)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1435)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1350)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:713)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:388)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:633)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:380)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:882)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1036)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1342873 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=39379)
[junit4] 2> 1342873 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 39379 try number 2 ...
[junit4] 2> 1342873 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10
[junit4] 2> 1342879 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1342879 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1342879 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1342879 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b5a0b12{/solr,null,AVAILABLE}
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1dfc1f2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39379}
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.s.Server Started @1342913ms
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:36055/solr, hostPort=39379}
[junit4] 2> 1342880 ERROR (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.1
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr
[junit4] 2> 1342880 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-08-06T16:15:18.983Z
[junit4] 2> 1342883 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1342887 INFO (zkConnectionManagerCallback-13346-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1342888 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1342888 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1342891 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1343214 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1343216 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7afafb26[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1343216 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7afafb26[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1343218 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@683dd7c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1343218 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@683dd7c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1343219 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36055/solr
[junit4] 2> 1343220 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1343223 INFO (zkConnectionManagerCallback-13357-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1343223 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1343224 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]-SendThread(127.0.0.1:36055)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10075c5aa0f000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10075c5aa0f000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10075c5aa0f000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1343271 INFO (OverseerCollectionConfigSetProcessor-72187085860044806-127.0.0.1:37521_solr-n_0000000000) [n:127.0.0.1:37521_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> 1343325 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1343326 INFO (zkConnectionManagerCallback-13359-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1343326 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1343330 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1343331 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1343334 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39379_solr as DOWN
[junit4] 2> 1343335 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1343335 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39379_solr
[junit4] 2> 1343336 INFO (zkCallback-13325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1343336 INFO (zkCallback-13294-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1343337 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1343338 WARN (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_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> 1343340 INFO (zkCallback-13358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1343352 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node1
[junit4] 2> 1343352 INFO (TEST-LeaderTragicEventTest.testOtherReplicasAreNotActive-seed#[6A06DC04BDA794FB]) [n:127.0.0.1:39379_solr ] o.a.s.c.CorePropertiesLocator Cores are: [testOtherReplicasAreNotActive_shard1_replica_n2]
[junit4] 2> 1343372 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 1343374 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1343374 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1343379 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 1343380 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 1343380 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1343380 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testOtherReplicasAreNotActive_shard1_replica_n2' using configuration from configset config, trusted=true
[junit4] 2> 1343380 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.c.SolrCore [[testOtherReplicasAreNotActive_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderTragicEventTest_6A06DC04BDA794FB-001/tempDir-001/node1/testOtherReplicasAreNotActive_shard1_replica_n2/data/]
[junit4] 2> 1343418 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1343418 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1343418 INFO (coreLoadExecutor-13365-thread-1-processing-n:127.0.0.1:39379_solr) [n:127.0.0.1:39379_solr c:testOtherReplicasAreNotActive s:shard1 r:core_node4 x:testOtherReplicasAreNotActive_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[ju
[...truncated too long message...]
mException: Unable to read additional data from server sessionid 0x10075d12ad80006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10075d12ad80006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2096830 WARN (jetty-launcher-19608-thread-2-processing-n:127.0.0.1:34319_solr-SendThread(127.0.0.1:34901)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10075d12ad80008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10075d12ad80008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10075d12ad80008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2096929 INFO (jetty-closer-19686-thread-1-processing-n:127.0.0.1:34319_solr) [ ] o.a.s.c.Overseer Overseer (id=72187135265341446-127.0.0.1:39159_solr-n_0000000000) closing
[junit4] 2> 2096930 INFO (jetty-closer-19686-thread-1-processing-n:127.0.0.1:34319_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1454f526{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2096930 INFO (jetty-closer-19686-thread-1-processing-n:127.0.0.1:34319_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@55bd9fc9{/solr,null,STOPPED}
[junit4] 2> 2096930 INFO (jetty-closer-19686-thread-1-processing-n:127.0.0.1:34319_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2097030 INFO (jetty-closer-19686-thread-2-processing-n:127.0.0.1:34319_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7ec63a9e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2097030 INFO (jetty-closer-19686-thread-2-processing-n:127.0.0.1:34319_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@41905e9{/solr,null,STOPPED}
[junit4] 2> 2097030 INFO (jetty-closer-19686-thread-2-processing-n:127.0.0.1:34319_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2097031 INFO (SUITE-SolrCloudReportersTest-seed#[6A06DC04BDA794FB]-worker) [n:127.0.0.1:34319_solr ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2097233 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> 5 /solr/collections/test_collection/terms/shard2
[junit4] 2> 5 /solr/collections/test_collection/terms/shard1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/configs/test
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/test_collection/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/collections/test_collection/state.json
[junit4] 2> 12 /solr/overseer/queue
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 2097236 INFO (SUITE-SolrCloudReportersTest-seed#[6A06DC04BDA794FB]-worker) [n:127.0.0.1:34319_solr ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34901
[junit4] 2> 2097236 INFO (SUITE-SolrCloudReportersTest-seed#[6A06DC04BDA794FB]-worker) [n:127.0.0.1:34319_solr ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34901
[junit4] 2> 2097237 INFO (SUITE-SolrCloudReportersTest-seed#[6A06DC04BDA794FB]-worker) [n:127.0.0.1:34319_solr ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34901
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.metrics.reporters.solr.SolrCloudReportersTest_6A06DC04BDA794FB-001
[junit4] 2> Aug 06, 2021 4:27:53 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=de-GR, timezone=Asia/Bahrain
[junit4] 2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=271543072,total=531628032
[junit4] 2> NOTE: All tests run in this JVM: [TestSQLHandlerNonCloud, CategoryRoutedAliasUpdateProcessorTest, TestFieldResource, ZkFailoverTest, CSVRequestHandlerTest, TestShardHandlerFactory, TestRTimerTree, TriggerCooldownIntegrationTest, HdfsLockFactoryTest, UpdateRequestProcessorFactoryTest, TestReload, IndexSizeTriggerTest, TestPayloadCheckQParserPlugin, MoveReplicaHDFSTest, ChaosMonkeyNothingIsSafeTest, DataDrivenBlockJoinTest, TestManagedResource, TestAnalyzeInfixSuggestions, TestBooleanSimilarityFactory, TestConfLoadPerf, JSONWriterTest, SpatialHeatmapFacetsTest, TestStressIncrementalBackup, QueryElevationComponentTest, DistanceFunctionTest, XmlInterpolationTest, SplitByPrefixTest, TestHashQParserPlugin, IndexSizeEstimatorTest, SolrMetricManagerTest, SuggestComponentTest, TestFilteredDocIdSet, DocValuesMissingTest, TestUseDocValuesAsStored2, TestFieldCacheWithThreads, PKIAuthenticationIntegrationTest, CloneFieldUpdateProcessorFactoryTest, RequiredFieldsTest, TestClusterProperties, TestNestedDocsSort, SyncSliceTest, TestCorePropertiesReload, BitVectorTest, TestUpdate, TestSolrDeletionPolicy2, TestSimExecutePlanAction, TestNonDefinedSimilarityFactory, TestDistributedMap, DistribDocExpirationUpdateProcessorTest, CopyFieldTest, ExternalFileFieldSortTest, HdfsAutoAddReplicasIntegrationTest, SpatialRPTFieldTypeTest, V2CollectionBackupsAPIMappingTest, ClusterStateTest, CdcrBidirectionalTest, TestPHPSerializedResponseWriter, TestRandomRequestDistribution, TestRuleBasedAuthorizationWithKerberos, RegexBoostProcessorTest, JvmMetricsTest, TestLRUCache, DocExpirationUpdateProcessorFactoryTest, SolrIndexSearcherTest, TestHashPartitioner, HLLUtilTest, DebugComponentTest, SimplePostToolTest, CdcrRequestHandlerTest, TestTrackingShardHandlerFactory, UnloadDistributedZkTest, TestTolerantUpdateProcessorCloud, FacetPivotSmallTest, TestNRTOpen, RulesTest, TestOmitPositions, TestDynamicURP, AssignBackwardCompatibilityTest, BlockCacheTest, BasicFunctionalityTest, SecurityConfHandlerTest, TestRetrieveFieldsOptimizer, PeerSyncTest, TestUnInvertedFieldException, MoreLikeThisHandlerTest, TestSchemalessBufferedUpdates, TestHalfAndHalfDocValues, TestWithCollection, TestIBSimilarityFactory, TestExactStatsCache, LeaderElectionIntegrationTest, TestUniqueKeyFieldResource, V2StandaloneTest, VersionInfoTest, ReindexCollectionTest, RankQParserPluginTest, TestSegmentSorting, TestSchemaResource, DirectSolrConnectionTest, TestExportTool, RandomizedTaggerTest, TestCrossCoreJoin, PackageManagerCLITest, SolrCoreTest, DistributedIntervalFacetingTest, BadComponentTest, TestInPlaceUpdatesRequiredField, TestNumericRangeQuery32, TestSurroundQueryParser, TestCloudPseudoReturnFields, TestDistributedTracing, DistributedFacetPivotWhiteBoxTest, TestSolrQueryParser, TestSolrCoreProperties, TestTolerantSearch, TestTrie, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, TestCharFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestLuceneIndexBackCompat, DeleteInactiveReplicaTest, DeleteReplicaTest, HttpPartitionTest, LeaderTragicEventTest, LeaderVoteWaitTimeoutTest, ReplicationFactorTest, TestLeaderElectionZkExpiry, TestOnReconnectListenerSupport, TestPullReplica, TestTlogReplayVsRecovery, TestUtilizeNode, ZkSolrClientTest, CollectionTooManyReplicasTest, ConcurrentCreateCollectionTest, ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest, HdfsCollectionsAPIDistributedZkTest, LocalFSCloudIncrementalBackupTest, TestHdfsCloudBackupRestore, AutoAddReplicasIntegrationTest, NodeLostTriggerIntegrationTest, RestoreTriggerStateTest, ScheduledTriggerTest, SearchRateTriggerIntegrationTest, SearchRateTriggerTest, TestSimComputePlanAction, TestSimDistribStateManager, TestSimLargeCluster, TestCSVLoader, TestHdfsBackupRestoreCore, TestReplicationHandlerBackup, TestReqParamsAPI, AdminHandlersProxyTest, AutoscalingHistoryHandlerTest, CoreAdminCreateDiscoverTest, CoreAdminHandlerTest, DaemonStreamApiTest, MetricsHandlerTest, SplitHandlerTest, TestApiFramework, TestCollectionAPIs, TestConfigsApi, DistributedExpandComponentTest, DistributedFacetPivotLargeTest, DistributedQueryComponentOptimizationTest, DistributedSuggestComponentTest, ShardsWhitelistTest, TestDistributedStatsComponentCardinality, SolrCloudReportersTest]
[junit4] Completed [746/939 (2!)] on J1 in 18.16s, 2 tests, 1 failure <<< FAILURES!
[...truncated 53108 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.9 - Build # 352 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.9/352/
1 tests failed.
FAILED: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test
Error Message:
.responseHeader.status:200!=0
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.status:200!=0
at __randomizedtesting.SeedInfo.seed([A9E03BB5E210110D:21B4046F4CEC7CF5]:0)
at junit.framework.Assert.fail(Assert.java:57)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:1006)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1033)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:679)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:642)
at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:191)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15904 lines...]
[junit4] Suite: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality
[junit4] 2> 1999568 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1999569 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/data-dir-179-001
[junit4] 2> 1999569 WARN (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=8 numCloses=8
[junit4] 2> 1999569 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1999571 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9062)
[junit4] 2> 1999571 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1999571 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[A9E03BB5E210110D]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /v_sve/b
[junit4] 2> 1999692 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/control/cores/collection1
[junit4] 2> 1999693 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1999693 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1999693 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10
[junit4] 2> 1999693 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1999693 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3006f9f8{/v_sve/b,null,AVAILABLE}
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1efdfb63{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37825}
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.Server Started @1999726ms
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/v_sve/b, hostPort=37825, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/control/cores}
[junit4] 2> 1999694 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.1
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr
[junit4] 2> 1999694 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-08-06T21:36:31.861Z
[junit4] 2> 1999695 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/control/solr.xml
[junit4] 2> 1999697 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1999697 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1999698 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2000065 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2000066 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@27fe0299[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2000066 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@27fe0299[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2000069 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@ecc5c83[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2000069 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@ecc5c83[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2000070 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] 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> 2000080 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2000080 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2000098 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2000105 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2000105 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2000106 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/control/cores
[junit4] 2> 2000106 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2000124 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2000157 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 2000157 WARN (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 2000158 WARN (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2000161 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2000168 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2000174 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2000174 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2000174 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 2000175 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5362147199695552]
[junit4] 2> 2000221 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2000221 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2000222 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2000222 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2000223 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@3ee6a0d6
[junit4] 2> 2000224 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2000238 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2000386 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2000387 INFO (coreLoadExecutor-23917-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1707381403212054528
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2000388 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2000389 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2000389 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2000390 INFO (searcherExecutor-23919-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2000531 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 2000532 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2000532 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2000532 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10
[junit4] 2> 2000534 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2000534 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2000534 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2000534 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ed8e6a7{/v_sve/b,null,AVAILABLE}
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5eb6afbb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39677}
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.Server Started @2000567ms
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/v_sve/b, hostPort=39677, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard0/cores}
[junit4] 2> 2000536 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.1
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-08-06T21:36:32.703Z
[junit4] 2> 2000536 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard0/solr.xml
[junit4] 2> 2000538 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2000538 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2000539 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2001813 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2001815 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@736ee7a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2001815 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@736ee7a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2001821 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5a8dbcaf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2001821 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5a8dbcaf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2001822 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] 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> 2001838 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2001838 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2001859 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2001870 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2001870 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2001872 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard0/cores
[junit4] 2> 2001872 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2001882 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2001931 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 2001931 WARN (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 2001931 WARN (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2001950 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2001962 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2002254 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2002254 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2002255 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 2002256 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5362147199695552]
[junit4] 2> 2002309 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2002309 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2002310 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2002310 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2002311 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@69c989b1
[junit4] 2> 2002312 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2002347 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2002445 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2002445 INFO (coreLoadExecutor-23936-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1707381405371072512
[junit4] 2> 2002446 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2002447 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2002449 INFO (searcherExecutor-23938-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2003292 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 2003293 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2003293 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2003293 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.Server jetty-9.4.41.v20210516; built: 2021-05-16T23:56:28.993Z; git: 98607f93c7833e7dc59489b13f3cb0a114fb9f4c; jvm 1.8.0_291-b10
[junit4] 2> 2003294 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2003294 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2003294 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2003294 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@64e0b1a6{/v_sve/b,null,AVAILABLE}
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4799ea1a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46021}
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.s.Server Started @2003327ms
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/v_sve/b, hostPort=46021, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard1/cores}
[junit4] 2> 2003295 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.1
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-08-06T21:36:35.462Z
[junit4] 2> 2003295 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard1/solr.xml
[junit4] 2> 2003297 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2003297 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2003299 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2004440 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2004440 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@55403fa4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2004440 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@55403fa4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2004444 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6f203d20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2004444 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6f203d20[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2004445 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] 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> 2004459 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2004459 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2004479 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2004490 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2004490 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2004492 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard1/cores
[junit4] 2> 2004492 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2004545 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2004561 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.1
[junit4] 2> 2004562 WARN (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <boolTofilterOptimizer> is currently not implemented and has no effect.
[junit4] 2> 2004562 WARN (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2004565 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2004572 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2004578 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2004578 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6ce14b07
[junit4] 2> 2004579 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard1/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001/tempDir-001/shard1/cores/collection1/data/]
[junit4] 2> 2004580 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5362147199695552]
[junit4] 2> 2004631 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2004631 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2004632 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2004632 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2004633 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@10c7af50
[junit4] 2> 2004635 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2004651 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2004880 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2004881 INFO (coreLoadExecutor-23955-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1707381407925403648
[junit4] 2> 2004882 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2004882 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2004882 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2004882 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2004882 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2004882 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2004883 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2004883 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2004883 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2004885 INFO (searcherExecutor-23957-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2005383 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2005383 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[A9E03BB5E210110D]) [ ] o.a.s.h.c.TestDistributedStatsComponentCardinality Building an index of 11677 docs
[junit4] 2> 2005393 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[1 (1707381408461225984)]} 0 1
[junit4] 2> 2005394 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[1 (1707381408463323136)]} 0 0
[junit4] 2> 2005395 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[2 (1707381408464371712)]} 0 0
[junit4] 2> 2005397 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[2 (1707381408465420288)]} 0 0
[junit4] 2> 2005397 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[3 (1707381408466468864)]} 0 0
[junit4] 2> 2005398 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[3 (1707381408467517440)]} 0 0
[junit4] 2> 2005398 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[4 (1707381408467517440)]} 0 0
[junit4] 2> 2005399 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[4 (1707381408468566016)]} 0 0
[junit4] 2> 2005399 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[5 (1707381408468566016)]} 0 0
[junit4] 2> 2005400 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[5 (1707381408469614592)]} 0 0
[junit4] 2> 2005401 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[6 (1707381408469614592)]} 0 0
[junit4] 2> 2005401 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[6 (1707381408470663168)]} 0 0
[junit4] 2> 2005402 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[7 (1707381408470663168)]} 0 0
[junit4] 2> 2005402 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[7 (1707381408471711744)]} 0 0
[junit4] 2> 2005403 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[8 (1707381408471711744)]} 0 0
[junit4] 2> 2005403 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[8 (1707381408472760320)]} 0 0
[junit4] 2> 2005404 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[9 (1707381408472760320)]} 0 0
[junit4] 2> 2005404 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[9 (1707381408473808896)]} 0 0
[junit4] 2> 2005405 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[10 (1707381408473808896)]} 0 0
[junit4] 2> 2005405 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[10 (1707381408474857472)]} 0 0
[junit4] 2> 2005406 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[11 (1707381408475906048)]} 0 0
[junit4] 2> 2005407 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[11 (1707381408475906048)]} 0 0
[junit4] 2> 2005407 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[12 (1707381408476954624)]} 0 0
[junit4] 2> 2005408 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[12 (1707381408476954624)]} 0 0
[junit4] 2> 2005408 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[13 (1707381408478003200)]} 0 0
[junit4] 2> 2005408 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[13 (1707381408478003200)]} 0 0
[junit4] 2> 2005409 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[14 (1707381408479051776)]} 0 0
[junit4] 2> 2005409 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[14 (1707381408479051776)]} 0 0
[junit4] 2> 2005410 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[15 (1707381408480100352)]} 0 0
[junit4] 2> 2005410 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[15 (1707381408480100352)]} 0 0
[junit4] 2> 2005411 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[16 (1707381408481148928)]} 0 0
[junit4] 2> 2005411 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[16 (1707381408481148928)]} 0 0
[junit4] 2> 2005420 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[17 (1707381408489537536)]} 0 0
[junit4] 2> 2005420 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[17 (1707381408490586112)]} 0 0
[junit4] 2> 2005421 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[18 (1707381408490586112)]} 0 0
[junit4] 2> 2005421 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[18 (1707381408491634688)]} 0 0
[junit4] 2> 2005422 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[19 (1707381408491634688)]} 0 0
[junit4] 2> 2005422 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[19 (1707381408492683264)]} 0 0
[junit4] 2> 2005422 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[20 (1707381408492683264)]} 0 0
[junit4] 2> 2005423 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[20 (1707381408493731840)]} 0 0
[junit4] 2> 2005423 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[21 (1707381408493731840)]} 0 0
[junit4] 2> 2005424 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[21 (1707381408494780416)]} 0 0
[junit4] 2> 2005424 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[22 (1707381408494780416)]} 0 0
[junit4] 2> 2005425 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[22 (1707381408495828992)]} 0 0
[junit4] 2> 2005425 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[23 (1707381408495828992)]} 0 0
[junit4] 2> 2005426 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[23 (1707381408496877568)]} 0 0
[junit4] 2> 2005426 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[24 (1707381408496877568)]} 0 0
[junit4] 2> 2005427 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[24 (1707381408497926144)]} 0 0
[junit4] 2> 2005427 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[25 (1707381408497926144)]} 0 0
[junit4] 2> 2005428 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[25 (1707381408498974720)]} 0 0
[junit4] 2> 2005428 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[26 (1707381408498974720)]} 0 0
[junit4] 2> 2005429 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[26 (1707381408498974720)]} 0 0
[junit4] 2> 2005429 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[27 (1707381408500023296)]} 0 0
[junit4] 2> 2005429 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[27 (1707381408500023296)]} 0 0
[junit4] 2> 2005430 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[28 (1707381408501071872)]} 0 0
[junit4] 2> 2005430 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[28 (1707381408501071872)]} 0 0
[junit4] 2> 2005431 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[29 (1707381408502120448)]} 0 0
[junit4] 2> 2005431 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[29 (1707381408502120448)]} 0 0
[junit4] 2> 2005432 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[30 (1707381408503169024)]} 0 0
[junit4] 2> 2005432 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[30 (1707381408503169024)]} 0 0
[junit4] 2> 2005433 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[31 (1707381408504217600)]} 0 0
[junit4] 2> 2005433 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[31 (1707381408504217600)]} 0 0
[junit4] 2> 2005434 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[32 (1707381408505266176)]} 0 0
[junit4] 2> 2005434 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[32 (1707381408505266176)]} 0 0
[junit4] 2> 2005435 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[33 (1707381408506314752)]} 0 0
[junit4] 2> 2005435 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[33 (1707381408506314752)]} 0 0
[junit4] 2> 2005436 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[34 (1707381408507363328)]} 0 0
[junit4] 2> 2005436 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[34 (1707381408507363328)]} 0 0
[junit4] 2> 2005437 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[35 (1707381408507363329)]} 0 0
[junit4] 2> 2005437 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[35 (1707381408508411904)]} 0 0
[junit4] 2> 2005437 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[36 (1707381408508411904)]} 0 0
[junit4] 2> 2005438 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[36 (1707381408509460480)]} 0 0
[junit4] 2> 2005438 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[37 (1707381408509460480)]} 0 0
[junit4] 2> 2005439 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[37 (1707381408510509056)]} 0 0
[junit4] 2> 2005439 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[38 (1707381408510509056)]} 0 0
[junit4] 2> 2005440 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[38 (1707381408511557632)]} 0 0
[junit4] 2> 2005440 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[39 (1707381408511557632)]} 0 0
[junit4] 2> 2005441 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[39 (1707381408512606208)]} 0 0
[junit4] 2> 2005441 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[40 (1707381408512606208)]} 0 0
[junit4] 2> 2005442 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[40 (1707381408513654784)]} 0 0
[junit4] 2> 2005442 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[41 (1707381408513654784)]} 0 0
[junit4] 2> 2005443 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[41 (1707381408514703360)]} 0 0
[junit4] 2> 2005443 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[42 (1707381408514703360)]} 0 0
[junit4] 2> 2005444 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[42 (1707381408514703360)]} 0 0
[junit4] 2> 2005444 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[43 (1707381408515751936)]} 0 0
[junit4] 2> 2005445 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[43 (1707381408515751936)]} 0 0
[junit4] 2> 2005445 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[44 (1707381408516800512)]} 0 0
[junit4] 2> 2005445 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[44 (1707381408516800512)]} 0 0
[junit4] 2> 2005446 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[45 (1707381408517849088)]} 0 0
[junit4] 2> 2005446 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[45 (1707381408517849088)]} 0 0
[junit4] 2> 2005447 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[46 (1707381408518897664)]} 0 0
[junit4] 2> 2005447 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[46 (1707381408518897664)]} 0 0
[junit4] 2> 2005448 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[47 (1707381408519946240)]} 0 0
[junit4] 2> 2005448 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[47 (1707381408519946240)]} 0 0
[junit4] 2> 2005449 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[48 (1707381408520994816)]} 0 0
[junit4] 2> 2005449 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[48 (1707381408520994816)]} 0 0
[junit4] 2> 2005450 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[49 (1707381408522043392)]} 0 0
[junit4] 2> 2005450 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[49 (1707381408522043392)]} 0 0
[junit4] 2> 2005451 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[50 (1707381408522043393)]} 0 0
[junit4] 2> 2005451 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[50 (1707381408523091968)]} 0 0
[junit4] 2> 2005452 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[51 (1707381408523091968)]} 0 0
[junit4] 2> 2005452 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[51 (1707381408524140544)]} 0 0
[junit4] 2> 2005452 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[52 (1707381408524140544)]} 0 0
[junit4] 2> 2005453 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[52 (1707381408525189120)]} 0 0
[junit4] 2> 2005453 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[53 (1707381408525189120)]} 0 0
[junit4] 2> 2005454 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[53 (1707381408526237696)]} 0 0
[junit4] 2> 2005454 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[54 (1707381408526237696)]} 0 0
[junit4] 2> 2005455 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[54 (1707381408527286272)]} 0 0
[junit4] 2> 2005455 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[55 (1707381408527286272)]} 0 0
[junit4] 2> 2005456 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[55 (1707381408528334848)]} 0 0
[junit4] 2> 2005456 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[56 (1707381408528334848)]} 0 0
[junit4] 2> 2005457 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[56 (1707381408528334848)]} 0 0
[junit4] 2> 2005457 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[57 (1707381408529383424)]} 0 0
[junit4] 2> 2005457 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[57 (1707381408529383424)]} 0 0
[junit4] 2> 2005458 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[58 (1707381408530432000)]} 0 0
[junit4] 2> 2005458 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[58 (1707381408530432000)]} 0 0
[junit4] 2> 2005459 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[59 (1707381408531480576)]} 0 0
[junit4] 2> 2005459 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[59 (1707381408531480576)]} 0 0
[junit4] 2> 2005460 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[60 (1707381408532529152)]} 0 0
[junit4] 2> 2005460 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[60 (1707381408532529152)]} 0 0
[junit4] 2> 2005461 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[61 (1707381408533577728)]} 0 0
[junit4] 2> 2005461 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[61 (1707381408533577728)]} 0 0
[junit4] 2> 2005462 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[62 (1707381408533577729)]} 0 0
[junit4] 2> 2005462 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[62 (1707381408534626304)]} 0 0
[junit4] 2> 2005463 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[63 (1707381408534626304)]} 0 0
[junit4] 2> 2005463 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[63 (1707381408535674880)]} 0 0
[junit4] 2> 2005463 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[64 (1707381408535674880)]} 0 0
[junit4] 2> 2005464 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[64 (1707381408536723456)]} 0 0
[junit4] 2> 2005464 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[65 (1707381408536723456)]} 0 0
[junit4] 2> 2005465 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[65 (1707381408537772032)]} 0 0
[junit4] 2> 2005465 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[66 (1707381408537772032)]} 0 0
[junit4] 2> 2005466 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[66 (1707381408538820608)]} 0 0
[junit4] 2> 2005469 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[67 (1707381408541966336)]} 0 0
[junit4] 2> 2005471 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[67 (1707381408544063488)]} 0 0
[junit4] 2> 2005471 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[68 (1707381408544063488)]} 0 0
[junit4] 2> 2005472 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[68 (1707381408545112064)]} 0 0
[junit4] 2> 2005472 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[69 (1707381408545112064)]} 0 0
[junit4] 2> 2005473 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[69 (1707381408546160640)]} 0 0
[junit4] 2> 2005474 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[70 (1707381408547209216)]} 0 0
[junit4] 2> 2005474 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[70 (1707381408547209216)]} 0 0
[junit4] 2> 2005475 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[71 (1707381408547209217)]} 0 0
[junit4] 2> 2005475 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[71 (1707381408548257792)]} 0 0
[junit4] 2> 2005476 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[72 (1707381408548257792)]} 0 0
[junit4] 2> 2005476 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[72 (1707381408549306368)]} 0 0
[junit4] 2> 2005476 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[73 (1707381408549306368)]} 0 0
[junit4] 2> 2005477 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[73 (1707381408550354944)]} 0 0
[junit4] 2> 2005478 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[74 (1707381408551403520)]} 0 0
[junit4] 2> 2005478 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[74 (1707381408551403520)]} 0 0
[junit4] 2> 2005479 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[75 (1707381408552452096)]} 0 0
[junit4] 2> 2005479 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[75 (1707381408552452096)]} 0 0
[junit4] 2> 2005480 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[76 (1707381408553500672)]} 0 0
[junit4] 2> 2005480 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[76 (1707381408553500672)]} 0 0
[junit4] 2> 2005481 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[77 (1707381408553500673)]} 0 0
[junit4] 2> 2005481 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[77 (1707381408554549248)]} 0 0
[junit4] 2> 2005482 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[78 (1707381408554549248)]} 0 0
[junit4] 2> 2005482 INFO (qtp681917907-42051) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[78 (1707381408555597824)]} 0 0
[junit4] 2> 2005482 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[79 (1707381408555597824)]} 0 0
[junit4] 2> 2005483 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[79 (1707381408556646400)]} 0 0
[junit4] 2> 2005483 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[80 (1707381408556646400)]} 0 0
[junit4] 2> 2005484 INFO (qtp851127673-42029) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[80 (1707381408557694976)]} 0 0
[junit4] 2> 2005484 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[81 (1707381408557694976)]} 0 0
[junit4] 2> 2005485 INFO (qtp681917907-42052) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[81 (1707381408558743552)]} 0 0
[junit4] 2> 2005485 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[82 (1707381408558743552)]} 0 0
[junit4] 2> 2005486 INFO (qtp851127673-42031) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[82 (1707381408559792128)]} 0 0
[junit4] 2> 2005486 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[83 (1707381408559792128)]} 0 0
[junit4] 2> 2005487 INFO (qtp681917907-42053) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[83 (1707381408560840704)]} 0 0
[junit4] 2> 2005487 INFO (qtp1398640305-42013) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[84 (1707381408560840704)]} 0 0
[junit4] 2> 2005488 INFO (qtp851127673-42032) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[84 (1707381408560840704)]} 0 0
[junit4] 2> 2005488 INFO (qtp1398640305-42008) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[85 (1707381408561889280)]} 0 0
[junit4] 2> 2005488 INFO (qtp681917907-42054) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[85 (1707381408561889280)]} 0 0
[junit4] 2> 2005489 INFO (qtp1398640305-42011) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[86 (1707381408562937856)]} 0 0
[junit4] 2> 2005489 INFO (qtp851127673-42033) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[86 (1707381408562937856)]} 0 0
[junit4] 2> 2005490 INFO (qtp1398640305-42012) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[87 (1707381408563986432)]} 0 0
[junit4] 2> 2005490 INFO (qtp681917907-42055) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[87 (1707381408563986432)]} 0 0
[junit4] 2> 2005491 INFO (qtp1398640305-42010) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{add=[88 (1707381408565035008)]} 0 0
[junit4] 2> 2005491 INFO (qtp851127673-42034) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/v_sve/b path=/update params={wt=javabin&version=2}{a
[...truncated too long message...]
eporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7dfdcb95: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@495f2af2
[junit4] 2> 2058039 INFO (closeThreadPool-23964-thread-5) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2058039 INFO (closeThreadPool-23964-thread-5) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14dc5094: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1ff65776
[junit4] 2> 2058042 INFO (closeThreadPool-23964-thread-5) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5eb6afbb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2058042 INFO (closeThreadPool-23964-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4ed8e6a7{/v_sve/b,null,STOPPED}
[junit4] 2> 2058042 INFO (closeThreadPool-23964-thread-5) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2058053 INFO (coreCloseExecutor-23973-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2058055 INFO (closeThreadPool-23964-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2058055 INFO (closeThreadPool-23964-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7f762bd2: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@62928af
[junit4] 2> 2058060 INFO (closeThreadPool-23964-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2058060 INFO (closeThreadPool-23964-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@20f1b76c: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@495f2af2
[junit4] 2> 2058062 INFO (closeThreadPool-23964-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2058062 INFO (closeThreadPool-23964-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c6dc1ea: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1ff65776
[junit4] 2> 2058065 INFO (closeThreadPool-23964-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4799ea1a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2058065 INFO (closeThreadPool-23964-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@64e0b1a6{/v_sve/b,null,STOPPED}
[junit4] 2> 2058065 INFO (closeThreadPool-23964-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedStatsComponentCardinality -Dtests.method=test -Dtests.seed=A9E03BB5E210110D -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Africa/Porto-Novo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 58.5s J1 | TestDistributedStatsComponentCardinality.test <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .responseHeader.status:200!=0
[junit4] > at __randomizedtesting.SeedInfo.seed([A9E03BB5E210110D:21B4046F4CEC7CF5]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:57)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:1006)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1033)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:679)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:642)
[junit4] > at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:191)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.9/solr/build/solr-core/test/J1/temp/solr.handler.component.TestDistributedStatsComponentCardinality_A9E03BB5E210110D-001
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=lt-LT, timezone=Africa/Porto-Novo
[junit4] 2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=261681528,total=534773760
[junit4] 2> NOTE: All tests run in this JVM: [TestSurroundQueryParser, TestRandomRequestDistribution, UpdateRequestProcessorFactoryTest, TestJsonFacetsWithNestedObjects, AutoAddReplicasPlanActionTest, TestXmlQParser, TestHashQParserPlugin, CoreSorterTest, TestPKIAuthenticationPlugin, IndexSizeTriggerTest, TestReplicationHandlerDiskOverFlow, LocalFSCloudIncrementalBackupTest, TestWithCollection, TestNonDefinedSimilarityFactory, VersionInfoTest, CategoryRoutedAliasUpdateProcessorTest, PackageManagerCLITest, DistributedFacetPivotWhiteBoxTest, CurrencyRangeFacetCloudTest, ChaosMonkeyNothingIsSafeTest, ZkFailoverTest, HdfsDirectoryFactoryTest, TestConfigSetProperties, NotRequiredUniqueKeyTest, UnloadDistributedZkTest, HdfsAutoAddReplicasIntegrationTest, TestDynamicURP, SearchRateTriggerIntegrationTest, TestSimNodeAddedTrigger, BasicFunctionalityTest, SuggestComponentTest, RoutingToNodesWithPropertiesTest, DataDrivenBlockJoinTest, TestUnInvertedFieldException, TestJsonFacetErrors, TestExplainDocTransformer, V2CollectionBackupsAPIMappingTest, TestSegmentSorting, TestElisionMultitermQuery, TestJoin, TestSimpleQParserPlugin, TestCloudPseudoReturnFields, TestAnalyzeInfixSuggestions, ReindexCollectionTest, TestReRankQParserPlugin, TestRetrieveFieldsOptimizer, CheckHdfsIndexTest, TestExceedMaxTermLength, V2StandaloneTest, SpellPossibilityIteratorTest, HdfsWriteToMultipleCollectionsTest, TestConfigSetsAPIZkFailure, UtilsToolTest, ExternalFileFieldSortTest, BasicAuthIntegrationTest, SimplePostToolTest, JvmMetricsTest, TestMiniSolrCloudClusterSSL, TestOmitPositions, DistribDocExpirationUpdateProcessorTest, TestSolrDeletionPolicy2, HdfsNNFailoverTest, CopyFieldTest, TestConfLoadPerf, TestDistributedTracing, TestLRUCache, NestedShardedAtomicUpdateTest, TestUnifiedSolrHighlighterWithoutStoredId, TestBooleanSimilarityFactory, ConcurrentDeleteAndCreateCollectionTest, SyncSliceTest, FileUtilsTest, TestSchemaResource, MoveReplicaHDFSTest, TestDocBasedVersionConstraints, TestShardHandlerFactory, BitVectorTest, QueryElevationComponentTest, TestClusterProperties, RandomizedTaggerTest, SolrCLIZkUtilsTest, SuggesterFSTTest, HdfsBasicDistributedZk2Test, SecurityConfHandlerTest, SplitByPrefixTest, TestCrossCoreJoin, HLLUtilTest, TestIBSimilarityFactory, SplitHandlerTest, AutoscalingHistoryHandlerTest, MoreLikeThisHandlerTest, TestCollectionAPIs, PreAnalyzedFieldManagedSchemaCloudTest, TestSimClusterStateProvider, TestFilteredDocIdSet, TestMissingGroups, TestSolrCachePerf, TestTrackingShardHandlerFactory, ClusterStateTest, HdfsSyncSliceTest, CdcrRequestHandlerTest, SolrShardReporterTest, JWTAuthPluginTest, TestHalfAndHalfDocValues, TestUniqueKeyFieldResource, CoreAdminRequestStatusTest, TestFieldResource, PeerSyncTest, TestDistributedMap, CollectionsAPISolrJTest, SpatialHeatmapFacetsTest, TolerantUpdateProcessorTest, CollectionTooManyReplicasTest, LeaderTragicEventTest, BasicDistributedZk2Test, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteNodeTest, DeleteReplicaTest, HttpPartitionWithTlogReplicasTest, LeaderFailoverAfterPartitionTest, MissingSegmentRecoveryTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerSolrResponseTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, ReplaceNodeTest, ShardRoutingCustomTest, ShardRoutingTest, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudRecovery2, TestPrepRecovery, TestPullReplicaWithAuth, TestQueryingOnDownCollection, TestSizeLimitedDistributedMap, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, TestTolerantUpdateProcessorRandomCloud, TlogReplayBufferedWhileIndexingTest, ZkCLITest, TestHdfsCloudBackupRestore, TestRequestStatusCollectionAPI, MetricTriggerTest, NodeAddedTriggerTest, ScheduledMaintenanceTriggerTest, TestPolicyCloud, TestSimDistributedQueue, TestSimTriggerIntegration, CdcrOpsAndBoundariesTest, HdfsRecoveryZkTest, HdfsTlogReplayBufferedWhileIndexingTest, TestClusterStateMutator, ZkCollectionPropsCachingTest, RuleEngineTest, RulesTest, TestBackupRepositoryFactory, TestBadConfig, TestConfigOverlay, TestCoreDiscovery, TestJmxIntegration, TestMinimalConfig, TestNRTOpen, TestQuerySenderListener, TestSolrCloudSnapshots, TestCSVLoader, TestContainerPlugin, TestSolrConfigHandlerCloud, CloudReplicaSourceTest, CustomTermsComponentTest, DistributedFacetPivotLongTailTest, DistributedQueryElevationComponentTest, InfixSuggestersTest, ShardsWhitelistTest, TestDistributedStatsComponentCardinality]
[junit4] Completed [739/939 (1!)] on J1 in 58.61s, 1 test, 1 failure <<< FAILURES!
[...truncated 53130 lines...]