You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/11/10 15:15:47 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-13.0.2) - Build # 1631
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1631/
Java: 64bit/jdk-13.0.2 -XX:-UseCompressedOops -XX:+UseG1GC
4 tests failed.
FAILED: org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica
Error Message:
Error from server at http://127.0.0.1:55417/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:55417/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
at __randomizedtesting.SeedInfo.seed([89D5F506FF7BEDD9:81E213CE24D59957]:0)
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:1173)
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.TestPullReplicaErrorHandling.testCantConnectToPullReplica(TestPullReplicaErrorHandling.java:149)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.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 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.base/java.lang.Thread.run(Thread.java:830)
FAILED: org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica
Error Message:
Error from server at http://127.0.0.1:57761/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:57761/solr: Cannot create collection pull_replica_error_handling_test_cant_connect_to_pull_replica. Value of maxShardsPerNode is 1, and the number of nodes currently live or live and part of your createNodeSet is 3. This allows a maximum of 3 to be created. Value of numShards is 2, value of nrtReplicas is 1, value of tlogReplicas is 0 and value of pullReplicas is 1. This requires 4 shards to be created (higher than the allowed number)
at __randomizedtesting.SeedInfo.seed([89D5F506FF7BEDD9:81E213CE24D59957]:0)
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:1173)
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.TestPullReplicaErrorHandling.testCantConnectToPullReplica(TestPullReplicaErrorHandling.java:149)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.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 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.base/java.lang.Thread.run(Thread.java:830)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.api.collections.SimpleCollectionCreateDeleteTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.api.collections.SimpleCollectionCreateDeleteTest: 1) Thread[id=26272, name=SessionTracker, state=TIMED_WAITING, group=TGRP-SimpleCollectionCreateDeleteTest] at java.base@13.0.2/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.api.collections.SimpleCollectionCreateDeleteTest:
1) Thread[id=26272, name=SessionTracker, state=TIMED_WAITING, group=TGRP-SimpleCollectionCreateDeleteTest]
at java.base@13.0.2/java.lang.Thread.sleep(Native Method)
at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157)
at __randomizedtesting.SeedInfo.seed([89D5F506FF7BEDD9]:0)
FAILED: org.apache.solr.cloud.autoscaling.ExecutePlanActionTest.testTaskTimeout
Error Message:
did not finish processing event in time
Stack Trace:
java.lang.AssertionError: did not finish processing event in time
at __randomizedtesting.SeedInfo.seed([89D5F506FF7BEDD9:8C88CDEC62132F4E]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.cloud.autoscaling.ExecutePlanActionTest.testTaskTimeout(ExecutePlanActionTest.java:317)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.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 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.base/java.lang.Thread.run(Thread.java:830)
Build Log:
[...truncated 15291 lines...]
[junit4] Suite: org.apache.solr.cloud.TestPullReplicaErrorHandling
[junit4] 2> 1792004 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1792004 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1792006 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/data-dir-146-001
[junit4] 2> 1792006 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 1792006 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1792008 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-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-5776") w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1792011 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/tempDir-001
[junit4] 2> 1792012 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1792013 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1792013 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1792016 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1792113 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 57707
[junit4] 2> 1792114 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:57707
[junit4] 2> 1792114 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57707
[junit4] 2> 1792114 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57707
[junit4] 2> 1792117 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792125 INFO (zkConnectionManagerCallback-11451-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792126 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792133 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792138 INFO (zkConnectionManagerCallback-11453-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792138 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792140 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792145 INFO (zkConnectionManagerCallback-11455-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792145 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792152 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840002, 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 0x100087781840002, 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> 1792255 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 1792255 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 1792256 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1792256 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1792256 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 1792263 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1792263 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1792263 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1792263 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1792264 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1792264 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1792264 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1792264 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1792266 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1792266 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31e1a483{/solr,null,AVAILABLE}
[junit4] 2> 1792267 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3112c6cb{/solr,null,AVAILABLE}
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5e4f03c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57718}
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12b5824d{/solr,null,AVAILABLE}
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.e.j.s.Server Started @1792347ms
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57718}
[junit4] 2> 1792268 ERROR (jetty-launcher-11456-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1bf45da2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57720}
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.e.j.s.Server Started @1792348ms
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57720}
[junit4] 2> 1792269 ERROR (jetty-launcher-11456-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-10T14:40:25.736541Z
[junit4] 2> 1792269 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@269f8d25{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57719}
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.e.j.s.Server Started @1792349ms
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57719}
[junit4] 2> 1792268 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-10T14:40:25.735992Z
[junit4] 2> 1792270 ERROR (jetty-launcher-11456-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-10T14:40:25.737567Z
[junit4] 2> 1792270 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792271 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1792271 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1792271 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1792272 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@227a87b6{/solr,null,AVAILABLE}
[junit4] 2> 1792272 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792273 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792273 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@47d7747{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57721}
[junit4] 2> 1792273 INFO (jetty-launcher-11456-thread-3) [ ] o.e.j.s.Server Started @1792353ms
[junit4] 2> 1792273 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57721}
[junit4] 2> 1792274 ERROR (jetty-launcher-11456-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1792274 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1792274 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1792274 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1792274 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1792274 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-10T14:40:25.741340Z
[junit4] 2> 1792276 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792277 INFO (zkConnectionManagerCallback-11458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792278 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792282 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792282 INFO (zkConnectionManagerCallback-11460-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792283 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792283 INFO (zkConnectionManagerCallback-11462-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792283 INFO (zkConnectionManagerCallback-11464-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792283 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792284 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792285 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792286 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792286 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1792288 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1792292 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1792294 WARN (jetty-launcher-11456-thread-1-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840005, 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 0x100087781840005, 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> 1792296 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1792296 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1792298 WARN (jetty-launcher-11456-thread-3-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840006.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840006, 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 0x100087781840006, 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> 1792444 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1792445 WARN (jetty-launcher-11456-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2520268f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792445 WARN (jetty-launcher-11456-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2520268f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792450 WARN (jetty-launcher-11456-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@8825bb8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792450 WARN (jetty-launcher-11456-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@8825bb8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792451 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57707/solr
[junit4] 2> 1792452 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792456 INFO (zkConnectionManagerCallback-11475-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792456 INFO (jetty-launcher-11456-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792459 WARN (jetty-launcher-11456-thread-2-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840007, 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 0x100087781840007, 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> 1792570 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792571 INFO (zkConnectionManagerCallback-11480-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792572 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792600 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1792610 WARN (jetty-launcher-11456-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@24b0fd93[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792613 WARN (jetty-launcher-11456-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@24b0fd93[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792631 WARN (jetty-launcher-11456-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@27216a2c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792631 WARN (jetty-launcher-11456-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@27216a2c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792635 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57707/solr
[junit4] 2> 1792641 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792645 INFO (zkConnectionManagerCallback-11493-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792655 INFO (jetty-launcher-11456-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792669 WARN (jetty-launcher-11456-thread-3-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840009.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840009, 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 0x100087781840009, 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> 1792803 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792814 INFO (zkConnectionManagerCallback-11498-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792823 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1792828 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792834 WARN (jetty-launcher-11456-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3247e2d0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792843 WARN (jetty-launcher-11456-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3247e2d0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792855 WARN (jetty-launcher-11456-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2a11f2d6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792855 WARN (jetty-launcher-11456-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2a11f2d6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1792857 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57707/solr
[junit4] 2> 1792859 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1792882 INFO (zkConnectionManagerCallback-11508-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1792882 INFO (jetty-launcher-11456-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1792888 WARN (jetty-launcher-11456-thread-4-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10008778184000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10008778184000b, 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 0x10008778184000b, 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> 1792980 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57721_solr
[junit4] 2> 1792988 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.c.Overseer Overseer (id=72066903404969994-127.0.0.1:57721_solr-n_0000000000) starting
[junit4] 2> 1792999 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793007 INFO (zkConnectionManagerCallback-11510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1793009 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793064 INFO (OverseerStateUpdate-72066903404969994-127.0.0.1:57721_solr-n_0000000000) [n:127.0.0.1:57721_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57721_solr
[junit4] 2> 1793067 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57721_solr
[junit4] 2> 1793078 INFO (OverseerStateUpdate-72066903404969994-127.0.0.1:57721_solr-n_0000000000) [n:127.0.0.1:57721_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1793078 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1793098 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1793102 WARN (jetty-launcher-11456-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@458e2be2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1793106 WARN (jetty-launcher-11456-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@458e2be2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1793112 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1793114 WARN (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_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> 1793134 WARN (jetty-launcher-11456-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@396be174[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1793134 WARN (jetty-launcher-11456-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@396be174[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1793155 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57707/solr
[junit4] 2> 1793162 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793170 INFO (zkConnectionManagerCallback-11525-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1793171 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57718_solr as DOWN
[junit4] 2> 1793176 INFO (jetty-launcher-11456-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793189 INFO (jetty-launcher-11456-thread-3) [n:127.0.0.1:57721_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/tempDir-001/node3
[junit4] 2> 1793189 WARN (jetty-launcher-11456-thread-1-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10008778184000d.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10008778184000d, 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 0x10008778184000d, 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> 1793192 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1793192 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57718_solr
[junit4] 2> 1793301 INFO (zkCallback-11509-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1793302 INFO (zkCallback-11497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1793303 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793305 INFO (zkConnectionManagerCallback-11530-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1793306 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793315 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1793317 WARN (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_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> 1793355 INFO (jetty-launcher-11456-thread-4) [n:127.0.0.1:57718_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/tempDir-001/node4
[junit4] 2> 1793391 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1793457 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57719_solr as DOWN
[junit4] 2> 1793465 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1793465 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57719_solr
[junit4] 2> 1793481 INFO (zkCallback-11509-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1793482 INFO (zkCallback-11529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1793483 INFO (zkCallback-11497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1793498 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1793499 WARN (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_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> 1793534 INFO (jetty-launcher-11456-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/tempDir-001/node1
[junit4] 2> 1793602 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1793650 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57720_solr as DOWN
[junit4] 2> 1793663 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1793663 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57720_solr
[junit4] 2> 1793669 INFO (zkCallback-11497-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793669 INFO (zkCallback-11509-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793670 INFO (zkCallback-11529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793670 INFO (zkCallback-11479-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1793675 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1793676 WARN (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_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> 1793699 INFO (jetty-launcher-11456-thread-2) [n:127.0.0.1:57720_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/tempDir-001/node2
[junit4] 2> 1793728 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1793729 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793735 INFO (zkConnectionManagerCallback-11549-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1793735 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793746 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1793758 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57707/solr ready
[junit4] 2> 1793783 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2085076380
[junit4] 2> 1793783 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57721_solr
[junit4] 2> 1793787 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1793788 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:57721_solr as DOWN
[junit4] 2> 1793801 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1793803 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1793803 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1793803 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1793803 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1793808 INFO (closeThreadPool-11554-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72066903404969994-127.0.0.1:57721_solr-n_0000000000) closing
[junit4] 2> 1793809 INFO (OverseerAutoScalingTriggerThread-72066903404969994-127.0.0.1:57721_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1793809 INFO (OverseerStateUpdate-72066903404969994-127.0.0.1:57721_solr-n_0000000000) [n:127.0.0.1:57721_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57721_solr
[junit4] 2> 1793841 INFO (closeThreadPool-11554-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72066903404969994-127.0.0.1:57721_solr-n_0000000000) closing
[junit4] 2> 1793842 INFO (zkCallback-11509-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57718_solr
[junit4] 2> 1793842 WARN (NIOWorkerThread-9) [ ] o.a.z.s.NIOServerCnxn Unexpected exception
[junit4] 2> => EndOfStreamException: Unable to read additional data from client, it probably closed the socket: address = /127.0.0.1:57734, session = 0x10008778184000a
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.handleFailedRead(NIOServerCnxn.java:163)
[junit4] 2> org.apache.zookeeper.server.ServerCnxn$EndOfStreamException: Unable to read additional data from client, it probably closed the socket: address = /127.0.0.1:57734, session = 0x10008778184000a
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.handleFailedRead(NIOServerCnxn.java:163) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:326) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory$IOWorkRequest.doWork(NIOServerCnxnFactory.java:522) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.server.WorkerService$ScheduledWorkRequest.run(WorkerService.java:154) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:830) [?:?]
[junit4] 2> 1793843 INFO (zkCallback-11529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1793843 INFO (zkCallback-11509-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1793843 INFO (zkCallback-11479-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1793843 INFO (zkCallback-11548-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1793848 INFO (zkCallback-11509-thread-1) [n:127.0.0.1:57718_solr ] o.a.s.c.Overseer Overseer (id=72066903404969996-127.0.0.1:57718_solr-n_0000000001) starting
[junit4] 2> 1793897 INFO (OverseerStateUpdate-72066903404969996-127.0.0.1:57718_solr-n_0000000001) [n:127.0.0.1:57718_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57718_solr
[junit4] 2> 1793937 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.Overseer Overseer (id=72066903404969994-127.0.0.1:57721_solr-n_0000000000) closing
[junit4] 2> 1793946 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@47d7747{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1793951 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@227a87b6{/solr,null,STOPPED}
[junit4] 2> 1793951 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1793953 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1793954 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1793954 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 1793961 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1793961 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1793961 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1793962 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@356079c0{/solr,null,AVAILABLE}
[junit4] 2> 1793963 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1134906c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57762}
[junit4] 2> 1793963 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.Server Started @1794042ms
[junit4] 2> 1793963 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57761}
[junit4] 2> 1793963 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1793964 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1793964 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1793964 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1793964 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1793964 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-10T14:40:27.431242Z
[junit4] 2> 1793965 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1793972 INFO (zkConnectionManagerCallback-11560-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1793972 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1793974 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1793981 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1794041 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840010.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840010, 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 0x100087781840010, 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> 1794262 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1794265 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4749572f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1794265 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4749572f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1794292 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2716c40d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1794292 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2716c40d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1794301 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57707/solr
[junit4] 2> 1794310 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1794322 INFO (zkConnectionManagerCallback-11571-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1794322 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1794336 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840011.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840011, 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 0x100087781840011, 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> 1794441 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1794452 INFO (zkConnectionManagerCallback-11573-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1794453 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1794505 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1794528 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57761_solr as DOWN
[junit4] 2> 1794534 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1794534 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57761_solr
[junit4] 2> 1794541 INFO (zkCallback-11529-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1794541 INFO (zkCallback-11572-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1794541 INFO (zkCallback-11548-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1794541 INFO (zkCallback-11479-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1794541 INFO (zkCallback-11509-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1794549 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1794550 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_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> 1794608 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57761_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001/tempDir-001/node3
[junit4] 2> 1794630 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1794646 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:57762/solr. Proxy: http://127.0.0.1:57761/solr
[junit4] 2> 1794648 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1325596179
[junit4] 2> 1794648 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:57718_solr
[junit4] 2> 1794652 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1794653 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:57718_solr as DOWN
[junit4] 2> 1794672 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 1794673 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 1794673 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 1794673 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1794680 INFO (closeThreadPool-11584-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72066903404969996-127.0.0.1:57718_solr-n_0000000001) closing
[junit4] 2> 1794681 INFO (OverseerAutoScalingTriggerThread-72066903404969996-127.0.0.1:57718_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1794682 INFO (OverseerStateUpdate-72066903404969996-127.0.0.1:57718_solr-n_0000000001) [n:127.0.0.1:57718_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:57718_solr
[junit4] 2> 1794694 INFO (closeThreadPool-11584-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72066903404969996-127.0.0.1:57718_solr-n_0000000001) closing
[junit4] 2> 1794702 WARN (jetty-launcher-11456-thread-4-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10008778184000c.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10008778184000c, 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 0x10008778184000c, 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> 1794706 INFO (zkCallback-11479-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1794706 INFO (zkCallback-11548-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1794706 INFO (zkCallback-11529-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57719_solr
[junit4] 2> 1794706 INFO (zkCallback-11529-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1794706 INFO (zkCallback-11572-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1794804 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.Overseer Overseer (id=72066903404969996-127.0.0.1:57718_solr-n_0000000001) closing
[junit4] 2> 1794810 INFO (zkCallback-11529-thread-1) [n:127.0.0.1:57719_solr ] o.a.s.c.Overseer Overseer (id=72066903404969998-127.0.0.1:57719_solr-n_0000000002) starting
[junit4] 2> 1794811 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5e4f03c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1794813 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@31e1a483{/solr,null,STOPPED}
[junit4] 2> 1794813 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1794815 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1794815 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1794815 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 1794819 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1794819 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1794819 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1794820 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41fbc954{/solr,null,AVAILABLE}
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4cc738ec{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:57771}
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.s.Server Started @1794901ms
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57767}
[junit4] 2> 1794822 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1794822 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-10T14:40:28.289978Z
[junit4] 2> 1794825 INFO (OverseerStateUpdate-72066903404969998-127.0.0.1:57719_solr-n_0000000002) [n:127.0.0.1:57719_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57719_solr
[junit4] 2> 1794826 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1794829 INFO (zkConnectionManagerCallback-11586-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1794829 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1794837 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1794853 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1794859 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840013.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840013, 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 0x100087781840013, 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> 1795229 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1795232 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1f2c59d2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1795232 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1f2c59d2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1795239 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@15637b06[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1795239 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@15637b06[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1795240 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57707/solr
[junit4] 2> 1795242 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1795247 INFO (zkConnectionManagerCallback-11601-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1795247 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1795250 WARN (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker-SendThread(127.0.0.1:57707)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100087781840014.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100087781840014, 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 0x100087781840014, 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> 1795355 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57767_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1795359 INFO (zkConnectionManagerCallback-11603-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1795359 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57767_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1795381 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57767_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1795399 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57767_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57767_solr as DOWN
[junit4] 2> 1795403 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57767_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1795403 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [n:127.0.0.1:57767_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57767_solr
[junit4] 2> 1795409 INFO (zkCallback-11548-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated
[...truncated too long message...]
plica_p2] CLOSING SolrCore org.apache.solr.core.SolrCore@1e6ae4e6
[junit4] 2> 103726 INFO (coreCloseExecutor-664-thread-1) [n:127.0.0.1:55433_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.pull_replica_error_handling_test_close_hooks_deleted_on_reconnect.shard1.replica_p2 tag=SolrCore@1e6ae4e6
[junit4] 2> 103726 INFO (coreCloseExecutor-664-thread-1) [n:127.0.0.1:55433_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.pull_replica_error_handling_test_close_hooks_deleted_on_reconnect.shard1.leader tag=SolrCore@1e6ae4e6
[junit4] 2> 103728 INFO (coreCloseExecutor-664-thread-1) [n:127.0.0.1:55433_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 103735 INFO (jetty-closer-645-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 103735 INFO (jetty-closer-645-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 103735 INFO (jetty-closer-645-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 103735 INFO (jetty-closer-645-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 103740 INFO (jetty-closer-645-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3cf8a2e8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 103741 INFO (jetty-closer-645-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7b9a174f{/solr,null,STOPPED}
[junit4] 2> 103741 INFO (jetty-closer-645-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 103743 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 103963 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> 12 /solr/aliases.json
[junit4] 2> 11 /solr/security.json
[junit4] 2> 9 /solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/terms/shard1
[junit4] 2> 9 /solr/configs/conf
[junit4] 2> 9 /solr/clusterprops.json
[junit4] 2> 8 /solr/packages.json
[junit4] 2> 8 /solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/terms/shard1
[junit4] 2> 6 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/terms/shard1
[junit4] 2> 4 /solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json
[junit4] 2> 4 /solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/state.json
[junit4] 2> 3 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json
[junit4] 2> 2 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 64 /solr/collections/pull_replica_error_handling_test_close_hooks_deleted_on_reconnect/state.json
[junit4] 2> 35 /solr/collections/pull_replica_error_handling_test_pull_replica_disconnects_from_zoo_keeper/state.json
[junit4] 2> 28 /solr/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json
[junit4] 2> 12 /solr/clusterstate.json
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 3 /solr/overseer_elect/election/72067033719701531-127.0.0.1:55441_solr-n_0000000007
[junit4] 2> 3 /solr/overseer_elect/election/72067033719701522-127.0.0.1:55417_solr-n_0000000004
[junit4] 2> 2 /solr/overseer_elect/election/72067033719701515-127.0.0.1:55394_solr-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/72067033719701525-127.0.0.1:55426_solr-n_0000000005
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 93 /solr/overseer/queue
[junit4] 2> 92 /solr/live_nodes
[junit4] 2> 47 /solr/collections
[junit4] 2> 26 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 4 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 4 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 103969 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:55384
[junit4] 2> 103969 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55384
[junit4] 2> 103969 INFO (SUITE-TestPullReplicaErrorHandling-seed#[89D5F506FF7BEDD9]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55384
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_89D5F506FF7BEDD9-001
[junit4] 2> Nov 10, 2020 3:14:15 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=FST50, fieldName_s=BlockTreeOrds(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{_version_=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1131, maxMBSortInHeap=7.574594772576936, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-BW, timezone=VST
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 13.0.2 (64-bit)/cpus=6,threads=1,free=64438280,total=166723584
[junit4] 2> NOTE: All tests run in this JVM: [ExecutePlanActionTest, TestPullReplicaErrorHandling]
[junit4] Completed [6/15 (1!)] on J2 in 42.01s, 4 tests, 1 error <<< FAILURES!
[...truncated 71 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/common-build.xml:1599: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/lucene/common-build.xml:1126: There were test failures: 15 suites, 55 tests, 1 error [seed: 89D5F506FF7BEDD9]
Total time: 3 minutes 2 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.api.collections.SimpleCollectionCreateDeleteTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ExecutePlanActionTest
[repro] 1/5 failed: org.apache.solr.cloud.TestPullReplicaErrorHandling
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Archiving artifacts
[Java] Sleeping for 5 seconds due to JENKINS-32191...
[Java] Parsing console log (workspace: '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX')
[Java] Successfully parsed console log
[Java] -> found 112 issues (skipped 103 duplicates)
[Java] Post processing issues on 'MacOSX VBOX' with source code encoding 'UTF-8'
[Java] Creating SCM blamer to obtain author and commit information for affected files
[Java] Creating SCM miner to obtain statistics for affected repository files
[Java] Resolving file names for all issues in source directory '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX'
[Java] -> resolved paths in source directory (53 found, 0 not found)
[Java] Resolving module names from module definitions (build.xml, pom.xml, or Manifest.mf files)
[Java] -> resolved module names for 112 issues
[Java] Resolving package names (or namespaces) by parsing the affected files
[Java] -> resolved package names of 53 affected files
[Java] No filter has been set, publishing all 112 issues
[Java] Creating fingerprints for all affected code blocks to track issues over different builds
[Java] -> created fingerprints for 112 issues (skipped 0 issues)
[Java] Copying affected files to Jenkins' build folder '/home/jenkins/jobs/Lucene-Solr-8.x-MacOSX/builds/1631/files-with-issues'
[Java] -> 53 copied, 0 not in workspace, 0 not-found, 0 with I/O error
[Java] Using reference build 'Lucene-Solr-8.x-MacOSX #1630' to compute new, fixed, and outstanding issues
[Java] Issues delta (vs. reference build): outstanding: 112, new: 0, fixed: 0
[Java] No quality gates have been set - skipping
[Java] Health report is disabled - skipping
[Java] Created analysis result for 112 issues (found 0 new issues, fixed 0 issues)
[Java] Attaching ResultAction with ID 'java' to run 'Lucene-Solr-8.x-MacOSX #1631'.
[Checks API] No suitable checks publisher found.
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[Checks API] No suitable checks publisher found.
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-14.0.1) - Build # 1632
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1632/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
4 tests failed.
FAILED: org.apache.solr.cloud.ReindexCollectionTest.testSameTargetReindexing
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([32935B1C6CB50BD]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ReindexCollectionTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([32935B1C6CB50BD]:0)
FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest.testMixedBounds
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([32935B1C6CB50BD]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([32935B1C6CB50BD]:0)
Build Log:
[...truncated 13820 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest
[junit4] 2> 365554 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 365561 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/data-dir-33-001
[junit4] 2> 365561 WARN (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 365561 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 365562 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 365562 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 365563 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001
[junit4] 2> 365563 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 365564 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 365564 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 365566 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 365675 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 55903
[junit4] 2> 365677 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:55903
[junit4] 2> 365677 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:55903
[junit4] 2> 365677 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 55903
[junit4] 2> 365704 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 365830 INFO (zkConnectionManagerCallback-2032-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 365831 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 365908 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 366071 INFO (zkConnectionManagerCallback-2034-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 366071 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 366075 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 366079 INFO (zkConnectionManagerCallback-2036-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 366081 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 366165 WARN (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker-SendThread(127.0.0.1:55903)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1000a9e754a0002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1000a9e754a0002, 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 0x1000a9e754a0002, 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> 366335 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 366335 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 366335 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 14.0.1+7
[junit4] 2> 366338 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 366338 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 366338 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 14.0.1+7
[junit4] 2> 366396 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 366396 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 366396 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 366410 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28565ff9{/solr,null,AVAILABLE}
[junit4] 2> 366480 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@33968212{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55909}
[junit4] 2> 366480 INFO (jetty-launcher-2037-thread-2) [ ] o.e.j.s.Server Started @366550ms
[junit4] 2> 366537 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55909}
[junit4] 2> 366537 ERROR (jetty-launcher-2037-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 366537 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 366538 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 366538 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 366538 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 366538 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-11T00:42:13.620169Z
[junit4] 2> 366507 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 366540 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 366541 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 366541 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 366547 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31a9e969{/solr,null,AVAILABLE}
[junit4] 2> 366557 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4f938391{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55910}
[junit4] 2> 366557 INFO (jetty-launcher-2037-thread-1) [ ] o.e.j.s.Server Started @366626ms
[junit4] 2> 366557 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55910}
[junit4] 2> 366596 ERROR (jetty-launcher-2037-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 366596 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 366597 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 366597 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 366597 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 366597 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-11-11T00:42:13.679269Z
[junit4] 2> 366603 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 366603 INFO (zkConnectionManagerCallback-2039-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 366603 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 366605 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 366612 INFO (zkConnectionManagerCallback-2041-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 366613 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 366615 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 366619 WARN (jetty-launcher-2037-thread-2-SendThread(127.0.0.1:55903)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1000a9e754a0003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1000a9e754a0003, 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 0x1000a9e754a0003, 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> 366677 WARN (jetty-launcher-2037-thread-1-SendThread(127.0.0.1:55903)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1000a9e754a0004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1000a9e754a0004, 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 0x1000a9e754a0004, 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> 367010 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 367015 WARN (jetty-launcher-2037-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@204b37ee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367015 WARN (jetty-launcher-2037-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@204b37ee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367055 WARN (jetty-launcher-2037-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ba99ed3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367055 WARN (jetty-launcher-2037-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ba99ed3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367056 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55903/solr
[junit4] 2> 367064 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 367111 INFO (zkConnectionManagerCallback-2055-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 367131 INFO (jetty-launcher-2037-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 367155 WARN (jetty-launcher-2037-thread-2-SendThread(127.0.0.1:55903)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1000a9e754a0005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1000a9e754a0005, 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 0x1000a9e754a0005, 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> 367192 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 367204 WARN (jetty-launcher-2037-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7e87e264[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367204 WARN (jetty-launcher-2037-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7e87e264[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367210 WARN (jetty-launcher-2037-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3dafba43[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367210 WARN (jetty-launcher-2037-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3dafba43[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 367212 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55903/solr
[junit4] 2> 367214 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 367220 INFO (zkConnectionManagerCallback-2063-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 367220 INFO (jetty-launcher-2037-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 367274 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 367278 INFO (zkConnectionManagerCallback-2065-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 367278 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 367437 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 367479 INFO (zkConnectionManagerCallback-2069-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 367479 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 375756 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55910_solr
[junit4] 2> 375876 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.c.Overseer Overseer (id=72069269726822408-127.0.0.1:55910_solr-n_0000000000) starting
[junit4] 2> 376526 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 376530 INFO (OverseerStateUpdate-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [n:127.0.0.1:55910_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:55910_solr
[junit4] 2> 376544 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55910_solr
[junit4] 2> 376590 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 376590 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 376590 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 376762 INFO (zkCallback-2068-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 376868 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 376870 WARN (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_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> 377014 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:55910_solr]
[junit4] 2> 377028 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 377038 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 377060 DEBUG (ScheduledTrigger-2074-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 377062 DEBUG (OverseerAutoScalingTriggerThread-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 377143 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55910.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 377267 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55910.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 377268 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55910.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 377269 INFO (jetty-launcher-2037-thread-1) [n:127.0.0.1:55910_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node1
[junit4] 2> 377771 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 377863 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:55909_solr as DOWN
[junit4] 2> 377883 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 377883 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55909_solr
[junit4] 2> 377926 INFO (zkCallback-2068-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 377972 INFO (zkCallback-2064-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 378068 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 378068 WARN (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_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> 378078 DEBUG (ScheduledTrigger-2074-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 378124 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 378217 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55909.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 378277 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55909.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 378278 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55909.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 378279 INFO (jetty-launcher-2037-thread-2) [n:127.0.0.1:55909_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node2
[junit4] 2> 378422 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 378450 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 378473 INFO (zkConnectionManagerCallback-2092-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 378473 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 378476 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 378479 INFO (SUITE-IndexSizeTriggerMixedBoundsTest-seed#[32935B1C6CB50BD]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55903/solr ready
[junit4] 2> 378667 INFO (TEST-IndexSizeTriggerMixedBoundsTest.testMixedBounds-seed#[32935B1C6CB50BD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMixedBounds
[junit4] 2> 378672 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=2&name=testMixedBounds_collection&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 378875 INFO (OverseerThreadFactory-2076-thread-1-processing-n:127.0.0.1:55910_solr) [n:127.0.0.1:55910_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testMixedBounds_collection
[junit4] 2> 379123 DEBUG (ScheduledTrigger-2074-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 379870 INFO (OverseerStateUpdate-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [n:127.0.0.1:55910_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testMixedBounds_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testMixedBounds_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55909/solr",
[junit4] 2> "node_name":"127.0.0.1:55909_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 380124 DEBUG (ScheduledTrigger-2074-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 380259 INFO (OverseerStateUpdate-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [n:127.0.0.1:55910_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testMixedBounds_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testMixedBounds_collection_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55910/solr",
[junit4] 2> "node_name":"127.0.0.1:55910_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 380453 INFO (OverseerStateUpdate-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [n:127.0.0.1:55910_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testMixedBounds_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testMixedBounds_collection_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55909/solr",
[junit4] 2> "node_name":"127.0.0.1:55909_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 380821 INFO (OverseerStateUpdate-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [n:127.0.0.1:55910_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testMixedBounds_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testMixedBounds_collection_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:55910/solr",
[junit4] 2> "node_name":"127.0.0.1:55910_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 381140 DEBUG (ScheduledTrigger-2074-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 381286 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 381293 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard1_replica_n3&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 381293 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 381319 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard2_replica_n6&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 381324 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard2_replica_n4&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 381601 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 381635 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 381669 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 381690 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 381725 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 381732 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testMixedBounds_collection_shard2_replica_n4' using configuration from configset conf, trusted=true
[junit4] 2> 381746 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55909.solr.core.testMixedBounds_collection.shard2.replica_n4' (registry 'solr.core.testMixedBounds_collection.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 381747 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.SolrCore [[testMixedBounds_collection_shard2_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node2/testMixedBounds_collection_shard2_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node2/testMixedBounds_collection_shard2_replica_n4/data/]
[junit4] 2> 381803 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 381805 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 381805 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testMixedBounds_collection_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 381806 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55909.solr.core.testMixedBounds_collection.shard1.replica_n1' (registry 'solr.core.testMixedBounds_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 381820 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[testMixedBounds_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node2/testMixedBounds_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node2/testMixedBounds_collection_shard1_replica_n1/data/]
[junit4] 2> 381830 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 383091 DEBUG (ScheduledTrigger-2074-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 383127 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 383129 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 383170 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'testMixedBounds_collection_shard1_replica_n3' using configuration from configset conf, trusted=true
[junit4] 2> 383171 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55910.solr.core.testMixedBounds_collection.shard1.replica_n3' (registry 'solr.core.testMixedBounds_collection.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 383171 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.SolrCore [[testMixedBounds_collection_shard1_replica_n3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node1/testMixedBounds_collection_shard1_replica_n3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node1/testMixedBounds_collection_shard1_replica_n3/data/]
[junit4] 2> 383212 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 383219 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 383219 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'testMixedBounds_collection_shard2_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 383220 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55910.solr.core.testMixedBounds_collection.shard2.replica_n6' (registry 'solr.core.testMixedBounds_collection.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47afaf11
[junit4] 2> 383220 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.SolrCore [[testMixedBounds_collection_shard2_replica_n6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node1/testMixedBounds_collection_shard2_replica_n6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest_32935B1C6CB50BD-001/tempDir-001/node1/testMixedBounds_collection_shard2_replica_n6/data/]
[junit4] 2> 383979 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 383982 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 383988 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 383988 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 383990 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 383990 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 383990 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 383990 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 383991 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 383991 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 383993 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 383993 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 384015 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 384016 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 384017 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 384019 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 384020 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 384021 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 384033 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 384033 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1683022519850762240
[junit4] 2> 384051 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 384051 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1683022519869636608
[junit4] 2> 384053 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 384054 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1683022519872782336
[junit4] 2> 384054 INFO (searcherExecutor-2100-thread-1-processing-n:127.0.0.1:55910_solr x:testMixedBounds_collection_shard2_replica_n6 c:testMixedBounds_collection s:shard2 r:core_node8) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.SolrCore [testMixedBounds_collection_shard2_replica_n6] Registered new searcher autowarm time: 0 ms
[junit4] 2> 384068 INFO (searcherExecutor-2098-thread-1-processing-n:127.0.0.1:55910_solr x:testMixedBounds_collection_shard1_replica_n3 c:testMixedBounds_collection s:shard1 r:core_node5) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.SolrCore [testMixedBounds_collection_shard1_replica_n3] Registered new searcher autowarm time: 0 ms
[junit4] 2> 384077 INFO (searcherExecutor-2094-thread-1-processing-n:127.0.0.1:55909_solr x:testMixedBounds_collection_shard2_replica_n4 c:testMixedBounds_collection s:shard2 r:core_node7) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.SolrCore [testMixedBounds_collection_shard2_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 384109 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 384109 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 384119 DEBUG (ScheduledTrigger-2074-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 384142 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMixedBounds_collection/terms/shard2 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 384160 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testMixedBounds_collection/leaders/shard2
[junit4] 2> 384244 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 384244 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 384295 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMixedBounds_collection/terms/shard1 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 384301 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMixedBounds_collection/terms/shard2 to Terms{values={core_node7=0, core_node8=0}, version=1}
[junit4] 2> 384302 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testMixedBounds_collection/leaders/shard1
[junit4] 2> 384302 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testMixedBounds_collection/leaders/shard2
[junit4] 2> 384317 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 384324 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 384325 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 384326 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1683022520157995008
[junit4] 2> 384387 INFO (searcherExecutor-2096-thread-1-processing-n:127.0.0.1:55909_solr x:testMixedBounds_collection_shard1_replica_n1 c:testMixedBounds_collection s:shard1 r:core_node2) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [testMixedBounds_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 384437 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 384438 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 384438 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/
[junit4] 2> 384442 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.PeerSync PeerSync: core=testMixedBounds_collection_shard2_replica_n6 url=http://127.0.0.1:55910/solr START replicas=[http://127.0.0.1:55909/solr/testMixedBounds_collection_shard2_replica_n4/] nUpdates=100
[junit4] 2> 384447 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.PeerSync PeerSync: core=testMixedBounds_collection_shard2_replica_n6 url=http://127.0.0.1:55910/solr DONE. We have no versions. sync failed.
[junit4] 2> 384453 INFO (qtp1028750280-5277) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.c.S.Request [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 384458 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 384461 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] 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> 384461 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testMixedBounds_collection/leaders/shard2/leader after winning as /collections/testMixedBounds_collection/leader_elect/shard2/election/72069269726822408-core_node8-n_0000000000
[junit4] 2> 384627 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMixedBounds_collection/terms/shard1 to Terms{values={core_node2=0, core_node5=0}, version=1}
[junit4] 2> 384628 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testMixedBounds_collection/leaders/shard1
[junit4] 2> 384800 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/ shard2
[junit4] 2> 384878 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 384878 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 384878 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/
[junit4] 2> 384910 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.PeerSync PeerSync: core=testMixedBounds_collection_shard1_replica_n3 url=http://127.0.0.1:55910/solr START replicas=[http://127.0.0.1:55909/solr/testMixedBounds_collection_shard1_replica_n1/] nUpdates=100
[junit4] 2> 384914 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.PeerSync PeerSync: core=testMixedBounds_collection_shard1_replica_n3 url=http://127.0.0.1:55910/solr DONE. We have no versions. sync failed.
[junit4] 2> 384955 INFO (qtp1028750280-5277) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.c.S.Request [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=27
[junit4] 2> 384960 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 384960 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] 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> 384960 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testMixedBounds_collection/leaders/shard1/leader after winning as /collections/testMixedBounds_collection/leader_elect/shard1/election/72069269726822408-core_node5-n_0000000000
[junit4] 2> 385090 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/ shard1
[junit4] 2> 385110 INFO (zkCallback-2068-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMixedBounds_collection/state.json] for collection [testMixedBounds_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 385144 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 385152 DEBUG (ScheduledTrigger-2074-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 385240 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard2_replica_n6&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3921
[junit4] 2> 385480 INFO (zkCallback-2068-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMixedBounds_collection/state.json] for collection [testMixedBounds_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 385485 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 385499 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard1_replica_n3&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4206
[junit4] 2> 385502 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard2_replica_n4&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4178
[junit4] 2> 385644 INFO (zkCallback-2064-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMixedBounds_collection/state.json] for collection [testMixedBounds_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 385644 INFO (zkCallback-2068-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMixedBounds_collection/state.json] for collection [testMixedBounds_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 385983 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testMixedBounds_collection_shard1_replica_n1&action=CREATE&numShards=2&collection=testMixedBounds_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=4698
[junit4] 2> 386050 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_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> 386088 INFO (zkCallback-2068-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMixedBounds_collection/state.json] for collection [testMixedBounds_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 386090 INFO (zkCallback-2064-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testMixedBounds_collection/state.json] for collection [testMixedBounds_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 386117 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=2&name=testMixedBounds_collection&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=7444
[junit4] 2> 386164 DEBUG (ScheduledTrigger-2074-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 386389 INFO (qtp1096054219-5287) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMixedBounds_collection/terms/shard1 to Terms{values={core_node2=1, core_node5=1}, version=2}
[junit4] 2> 386568 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testMixedBounds_collection/terms/shard2 to Terms{values={core_node7=1, core_node8=1}, version=2}
[junit4] 2> 386793 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-0-0 (1683022522151337984), id-100-0 (1683022522291847168), id-300-0 (1683022522292895744), id-500-0 (1683022522292895745), id-700-0 (1683022522292895746), id-800-0 (1683022522293944320), id-900-0 (1683022522293944321), id-1200-0 (1683022522293944322), id-1300-0 (1683022522293944323), id-1400-0 (1683022522293944324), ... (57 adds)]} 0 357
[junit4] 2> 386866 INFO (qtp1096054219-5287) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-0 (1683022522151337984), id-100-0 (1683022522291847168), id-300-0 (1683022522292895744), id-500-0 (1683022522292895745), id-700-0 (1683022522292895746), id-800-0 (1683022522293944320), id-900-0 (1683022522293944321), id-1200-0 (1683022522293944322), id-1300-0 (1683022522293944323), id-1400-0 (1683022522293944324), ... (57 adds)]} 0 689
[junit4] 2> 386871 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-200-0 (1683022522150289408), id-400-0 (1683022522290798592), id-600-0 (1683022522291847168), id-1000-0 (1683022522292895744), id-1100-0 (1683022522293944320), id-2000-0 (1683022522293944321), id-2300-0 (1683022522294992896), id-2400-0 (1683022522294992897), id-2800-0 (1683022522296041472), id-2900-0 (1683022522297090048), ... (43 adds)]} 0 459
[junit4] 2> 386915 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-200-0 (1683022522150289408), id-400-0 (1683022522290798592), id-600-0 (1683022522291847168), id-1000-0 (1683022522292895744), id-1100-0 (1683022522293944320), id-2000-0 (1683022522293944321), id-2300-0 (1683022522294992896), id-2400-0 (1683022522294992897), id-2800-0 (1683022522296041472), id-2900-0 (1683022522297090048), ... (43 adds)]} 0 719
[junit4] 2> 387099 INFO (qtp1028750280-5277) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-100-1 (1683022522892681216), id-200-1 (1683022522892681217), id-600-1 (1683022522893729792), id-1000-1 (1683022522893729793), id-1100-1 (1683022522893729794), id-1300-1 (1683022522893729795), id-1400-1 (1683022522893729796), id-1500-1 (1683022522894778368), id-1700-1 (1683022522894778369), id-1900-1 (1683022522894778370), ... (51 adds)]} 0 161
[junit4] 2> 387099 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-0-1 (1683022522890584064), id-300-1 (1683022522892681216), id-400-1 (1683022522892681217), id-500-1 (1683022522892681218), id-700-1 (1683022522892681219), id-800-1 (1683022522893729792), id-900-1 (1683022522893729793), id-1200-1 (1683022522893729794), id-1600-1 (1683022522893729795), id-1800-1 (1683022522893729796), ... (49 adds)]} 0 154
[junit4] 2> 387127 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-100-1 (1683022522892681216), id-200-1 (1683022522892681217), id-600-1 (1683022522893729792), id-1000-1 (1683022522893729793), id-1100-1 (1683022522893729794), id-1300-1 (1683022522893729795), id-1400-1 (1683022522893729796), id-1500-1 (1683022522894778368), id-1700-1 (1683022522894778369), id-1900-1 (1683022522894778370), ... (51 adds)]} 0 194
[junit4] 2> 387130 INFO (qtp1096054219-5279) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-1 (1683022522890584064), id-300-1 (1683022522892681216), id-400-1 (1683022522892681217), id-500-1 (1683022522892681218), id-700-1 (1683022522892681219), id-800-1 (1683022522893729792), id-900-1 (1683022522893729793), id-1200-1 (1683022522893729794), id-1600-1 (1683022522893729795), id-1800-1 (1683022522893729796), ... (49 adds)]} 0 199
[junit4] 2> 387186 DEBUG (ScheduledTrigger-2074-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 387254 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-100-2 (1683022523130707968), id-200-2 (1683022523130707969), id-300-2 (1683022523131756544), id-400-2 (1683022523131756545), id-600-2 (1683022523131756546), id-700-2 (1683022523131756547), id-1000-2 (1683022523131756548), id-1200-2 (1683022523131756549), id-1300-2 (1683022523132805120), id-1400-2 (1683022523132805121), ... (46 adds)]} 0 79
[junit4] 2> 387254 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-0-2 (1683022523168456704), id-500-2 (1683022523168456705), id-800-2 (1683022523169505280), id-900-2 (1683022523169505281), id-1100-2 (1683022523169505282), id-1600-2 (1683022523169505283), id-1800-2 (1683022523169505284), id-2000-2 (1683022523170553856), id-2200-2 (1683022523170553857), id-2400-2 (1683022523170553858), ... (54 adds)]} 0 19
[junit4] 2> 387313 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-100-2 (1683022523130707968), id-200-2 (1683022523130707969), id-300-2 (1683022523131756544), id-400-2 (1683022523131756545), id-600-2 (1683022523131756546), id-700-2 (1683022523131756547), id-1000-2 (1683022523131756548), id-1200-2 (1683022523131756549), id-1300-2 (1683022523132805120), id-1400-2 (1683022523132805121), ... (46 adds)]} 0 157
[junit4] 2> 387313 INFO (qtp1096054219-5287) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-2 (1683022523168456704), id-500-2 (1683022523168456705), id-800-2 (1683022523169505280), id-900-2 (1683022523169505281), id-1100-2 (1683022523169505282), id-1600-2 (1683022523169505283), id-1800-2 (1683022523169505284), id-2000-2 (1683022523170553856), id-2200-2 (1683022523170553857), id-2400-2 (1683022523170553858), ... (54 adds)]} 0 118
[junit4] 2> 387481 INFO (qtp1028750280-5277) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-0-3 (1683022523411726336), id-100-3 (1683022523412774912), id-200-3 (1683022523412774913), id-300-3 (1683022523412774914), id-700-3 (1683022523412774915), id-800-3 (1683022523412774916), id-900-3 (1683022523412774917), id-1000-3 (1683022523412774918), id-1700-3 (1683022523413823488), id-1800-3 (1683022523413823489), ... (43 adds)]} 0 26
[junit4] 2> 387482 INFO (OverseerCollectionConfigSetProcessor-72069269726822408-127.0.0.1:55910_solr-n_0000000000) [n:127.0.0.1:55910_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> 387516 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-3 (1683022523411726336), id-100-3 (1683022523412774912), id-200-3 (1683022523412774913), id-300-3 (1683022523412774914), id-700-3 (1683022523412774915), id-800-3 (1683022523412774916), id-900-3 (1683022523412774917), id-1000-3 (1683022523412774918), id-1700-3 (1683022523413823488), id-1800-3 (1683022523413823489), ... (43 adds)]} 0 87
[junit4] 2> 387549 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-400-3 (1683022523456815104), id-500-3 (1683022523459960832), id-600-3 (1683022523459960833), id-1100-3 (1683022523461009408), id-1200-3 (1683022523461009409), id-1300-3 (1683022523461009410), id-1400-3 (1683022523490369536), id-1500-3 (1683022523490369537), id-1600-3 (1683022523490369538), id-1900-3 (1683022523491418112), ... (57 adds)]} 0 41
[junit4] 2> 387554 INFO (qtp1096054219-5279) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-400-3 (1683022523456815104), id-500-3 (1683022523459960832), id-600-3 (1683022523459960833), id-1100-3 (1683022523461009408), id-1200-3 (1683022523461009409), id-1300-3 (1683022523461009410), id-1400-3 (1683022523490369536), id-1500-3 (1683022523490369537), id-1600-3 (1683022523490369538), id-1900-3 (1683022523491418112), ... (57 adds)]} 0 82
[junit4] 2> 387604 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-0-4 (1683022523560624128), id-100-4 (1683022523560624129), id-500-4 (1683022523560624130), id-800-4 (1683022523560624131), id-1000-4 (1683022523560624132), id-1400-4 (1683022523561672704), id-2000-4 (1683022523561672705), id-2200-4 (1683022523561672706), id-2400-4 (1683022523561672707), id-2800-4 (1683022523561672708), ... (47 adds)]} 0 28
[junit4] 2> 387606 INFO (qtp1096054219-5287) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-4 (1683022523560624128), id-100-4 (1683022523560624129), id-500-4 (1683022523560624130), id-800-4 (1683022523560624131), id-1000-4 (1683022523560624132), id-1400-4 (1683022523561672704), id-2000-4 (1683022523561672705), id-2200-4 (1683022523561672706), id-2400-4 (1683022523561672707), id-2800-4 (1683022523561672708), ... (47 adds)]} 0 36
[junit4] 2> 387629 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-200-4 (1683022523562721280), id-300-4 (1683022523563769856), id-400-4 (1683022523563769857), id-600-4 (1683022523563769858), id-700-4 (1683022523563769859), id-900-4 (1683022523563769860), id-1100-4 (1683022523564818432), id-1200-4 (1683022523564818433), id-1300-4 (1683022523564818434), id-1500-4 (1683022523564818435), ... (53 adds)]} 0 44
[junit4] 2> 387635 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-200-4 (1683022523562721280), id-300-4 (1683022523563769856), id-400-4 (1683022523563769857), id-600-4 (1683022523563769858), id-700-4 (1683022523563769859), id-900-4 (1683022523563769860), id-1100-4 (1683022523564818432), id-1200-4 (1683022523564818433), id-1300-4 (1683022523564818434), id-1500-4 (1683022523564818435), ... (53 adds)]} 0 62
[junit4] 2> 387715 INFO (qtp1028750280-5277) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-0-5 (1683022523664433152), id-100-5 (1683022523664433153), id-300-5 (1683022523665481728), id-500-5 (1683022523665481729), id-600-5 (1683022523665481730), id-700-5 (1683022523665481731), id-800-5 (1683022523665481732), id-1700-5 (1683022523665481733), id-2000-5 (1683022523665481734), id-2200-5 (1683022523665481735), ... (50 adds)]} 0 37
[junit4] 2> 387719 INFO (qtp1096054219-5279) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-5 (1683022523664433152), id-100-5 (1683022523664433153), id-300-5 (1683022523665481728), id-500-5 (1683022523665481729), id-600-5 (1683022523665481730), id-700-5 (1683022523665481731), id-800-5 (1683022523665481732), id-1700-5 (1683022523665481733), id-2000-5 (1683022523665481734), id-2200-5 (1683022523665481735), ... (50 adds)]} 0 49
[junit4] 2> 387733 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-200-5 (1683022523648704512), id-400-5 (1683022523649753088), id-900-5 (1683022523649753089), id-1000-5 (1683022523649753090), id-1100-5 (1683022523649753091), id-1200-5 (1683022523649753092), id-1300-5 (1683022523649753093), id-1400-5 (1683022523649753094), id-1500-5 (1683022523649753095), id-1600-5 (1683022523650801664), ... (50 adds)]} 0 7
[junit4] 2> 387737 INFO (qtp1096054219-5288) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-200-5 (1683022523648704512), id-400-5 (1683022523649753088), id-900-5 (1683022523649753089), id-1000-5 (1683022523649753090), id-1100-5 (1683022523649753091), id-1200-5 (1683022523649753092), id-1300-5 (1683022523649753093), id-1400-5 (1683022523649753094), id-1500-5 (1683022523649753095), id-1600-5 (1683022523650801664), ... (50 adds)]} 0 82
[junit4] 2> 387790 INFO (qtp1028750280-5278) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard2 r:core_node7 x:testMixedBounds_collection_shard2_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n4] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard2_replica_n6/&wt=javabin&version=2}{add=[id-0-6 (1683022523745173504), id-100-6 (1683022523746222080), id-200-6 (1683022523746222081), id-300-6 (1683022523746222082), id-600-6 (1683022523746222083), id-900-6 (1683022523746222084), id-1000-6 (1683022523746222085), id-1300-6 (1683022523747270656), id-1600-6 (1683022523747270657), id-1700-6 (1683022523747270658), ... (48 adds)]} 0 37
[junit4] 2> 387811 INFO (qtp1096054219-5286) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard2 r:core_node8 x:testMixedBounds_collection_shard2_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard2_replica_n6] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-0-6 (1683022523745173504), id-100-6 (1683022523746222080), id-200-6 (1683022523746222081), id-300-6 (1683022523746222082), id-600-6 (1683022523746222083), id-900-6 (1683022523746222084), id-1000-6 (1683022523746222085), id-1300-6 (1683022523747270656), id-1600-6 (1683022523747270657), id-1700-6 (1683022523747270658), ... (48 adds)]} 0 66
[junit4] 2> 387817 INFO (qtp1028750280-5281) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-400-6 (1683022523743076352), id-500-6 (1683022523749367808), id-700-6 (1683022523749367809), id-800-6 (1683022523749367810), id-1100-6 (1683022523750416384), id-1200-6 (1683022523750416385), id-1400-6 (1683022523750416386), id-1500-6 (1683022523750416387), id-1800-6 (1683022523750416388), id-2200-6 (1683022523750416389), ... (52 adds)]} 0 40
[junit4] 2> 387828 INFO (qtp1096054219-5287) [n:127.0.0.1:55910_solr c:testMixedBounds_collection s:shard1 r:core_node5 x:testMixedBounds_collection_shard1_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n3] webapp=/solr path=/update params={_stateVer_=testMixedBounds_collection:8&collection=testMixedBounds_collection&wt=javabin&version=2}{add=[id-400-6 (1683022523743076352), id-500-6 (1683022523749367808), id-700-6 (1683022523749367809), id-800-6 (1683022523749367810), id-1100-6 (1683022523750416384), id-1200-6 (1683022523750416385), id-1400-6 (1683022523750416386), id-1500-6 (1683022523750416387), id-1800-6 (1683022523750416388), id-2200-6 (1683022523750416389), ... (52 adds)]} 0 83
[junit4] 2> 387877 INFO (qtp1028750280-5280) [n:127.0.0.1:55909_solr c:testMixedBounds_collection s:shard1 r:core_node2 x:testMixedBounds_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [testMixedBounds_collection_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:55910/solr/testMixedBounds_collection_shard1_replica_n3/&wt=javabin&version=2}{add=[id-100-7 (1683022523835351040), id-300-7 (1683022523835351041), id-400-7 (1683022523835351042), id-500-7 (1683022523836399616), id-600-7 (1683022523836399617), id-700-7 (1683022523836399618), id-800-7 (1683022523836399619), id-1100-7 (1683022523836399620),
[...truncated too long message...]
at java.base@14.0.1/sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:122)
[junit4] 2> at java.base@14.0.1/sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:129)
[junit4] 2> at java.base@14.0.1/sun.nio.ch.SelectorImpl.select(SelectorImpl.java:146)
[junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector.nioSelect(ManagedSelector.java:149)
[junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector.select(ManagedSelector.java:156)
[junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:572)
[junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:509)
[junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produceTask(EatWhatYouKill.java:360)
[junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:184)
[junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171)
[junit4] 2> at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:135)
[junit4] 2> at app//org.eclipse.jetty.io.ManagedSelector$$Lambda$263/0x0000000800e19840.run(Unknown Source)
[junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:773)
[junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:905)
[junit4] 2> at java.base@14.0.1/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 12) Thread[id=4719, name=qtp250336522-4719, state=TIMED_WAITING, group=TGRP-ReindexCollectionTest]
[junit4] 2> at java.base@14.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:252)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1661)
[junit4] 2> at app//org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:842)
[junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:892)
[junit4] 2> at java.base@14.0.1/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 13) Thread[id=4711, name=qtp250336522-4711, state=TIMED_WAITING, group=TGRP-ReindexCollectionTest]
[junit4] 2> at java.base@14.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:252)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1661)
[junit4] 2> at app//org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:382)
[junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.idleJobPoll(QueuedThreadPool.java:842)
[junit4] 2> at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:892)
[junit4] 2> at java.base@14.0.1/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 14) Thread[id=4721, name=Session-HouseKeeper-567e740d-1, state=TIMED_WAITING, group=TGRP-ReindexCollectionTest]
[junit4] 2> at java.base@14.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:252)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:1661)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1056)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1116)
[junit4] 2> at java.base@14.0.1/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:630)
[junit4] 2> at java.base@14.0.1/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 517744 WARN (jetty-launcher-2777-thread-1-SendThread(127.0.0.1:55979)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1000a9f0bab0008.
[junit4] 2> => java.net.ConnectException: Connection refused
[junit4] 2> at java.base/sun.nio.ch.Net.pollConnect(Native Method)
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.Net.pollConnect(Native Method) ~[?:?]
[junit4] 2> at sun.nio.ch.Net.pollConnectNow(Net.java:589) ~[?:?]
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:839) ~[?:?]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:344) ~[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> NOTE: test params are: codec=Asserting(Lucene87): {remove_s=FST50, _root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), string_s=PostingsFormat(name=Direct), sind=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=Lucene84}, docValues:{_version_=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1540, maxMBSortInHeap=7.0713188454829545, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ce, timezone=UCT
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 14.0.1 (64-bit)/cpus=6,threads=21,free=116850264,total=345706496
[junit4] 2> NOTE: All tests run in this JVM: [ZkStateWriterTest, SolrShardReporterTest, TestInfoStreamLogging, TestCustomDocTransformer, TestSolrXml, URLClassifyProcessorTest, UninvertDocValuesMergePolicyTest, DirectUpdateHandlerTest, TestDownShardTolerantSearch, TestRemoteStreaming, CoreAdminOperationTest, TestHttpShardHandlerFactory, TestJsonRangeFacets, ConcurrentCreateRoutedAliasTest, TestTolerantUpdateProcessorCloud, TestSolrCLIRunExample, SolrCLIZkUtilsTest, HdfsDirectoryFactoryTest, IndexSchemaTest, AuditLoggerIntegrationTest, BlockJoinFacetSimpleTest, RangeFacetCloudTest, TestDocSet, CacheHeaderTest, TestRestoreCore, TestSimComputePlanAction, TestApiFramework, TriggerCooldownIntegrationTest, TestDocTermOrdsUninvertLimit, TestInPlaceUpdatesStandalone, SolrTestCaseJ4Test, TestChildDocTransformer, ZkSolrClientTest, HdfsAutoAddReplicasIntegrationTest, JsonLoaderTest, RequiredFieldsTest, TestConfigSetsAPIZkFailure, SparseHLLTest, TestCopyFieldCollectionResource, TestFreeTextSuggestions, ReindexCollectionTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ReindexCollectionTest -Dtests.seed=32935B1C6CB50BD -Dtests.slow=true -Dtests.locale=ce -Dtests.timezone=UCT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | ReindexCollectionTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([32935B1C6CB50BD]:0)
[junit4] Completed [553/921 (2!)] on J2 in 113.95s, 6 tests, 2 errors <<< FAILURES!
[...truncated 44695 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1632/consoleText
[repro] Revision: e76a25ebc72520ee3cbbeca31df82dd3a3a31048
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=IndexSizeTriggerMixedBoundsTest -Dtests.method=testMixedBounds -Dtests.seed=32935B1C6CB50BD -Dtests.slow=true -Dtests.locale=sq-MK -Dtests.timezone=Asia/Aden -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=IndexSizeTriggerMixedBoundsTest -Dtests.seed=32935B1C6CB50BD -Dtests.slow=true -Dtests.locale=sq-MK -Dtests.timezone=Asia/Aden -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=ReindexCollectionTest -Dtests.method=testSameTargetReindexing -Dtests.seed=32935B1C6CB50BD -Dtests.slow=true -Dtests.locale=ce -Dtests.timezone=UCT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=ReindexCollectionTest -Dtests.seed=32935B1C6CB50BD -Dtests.slow=true -Dtests.locale=ce -Dtests.timezone=UCT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] ReindexCollectionTest
[repro] IndexSizeTriggerMixedBoundsTest
[repro] ant compile-test
[...truncated 3455 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.ReindexCollectionTest|*.IndexSizeTriggerMixedBoundsTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=32935B1C6CB50BD -Dtests.slow=true -Dtests.locale=ce -Dtests.timezone=UCT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 131 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.ReindexCollectionTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.IndexSizeTriggerMixedBoundsTest
[repro] Exiting with code 0
[...truncated 93 lines...]