You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/01/18 00:15:28 UTC
[JENKINS] Lucene » Lucene-Solr-NightlyTests-8.x - Build # 156 - Still unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.x/156/
4 tests failed.
FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.test
Error Message:
Error from server at http://127.0.0.1:35577/ig_zbo/z: Could not fully create collection: routeFieldColl
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:35577/ig_zbo/z: Could not fully create collection: routeFieldColl
at __randomizedtesting.SeedInfo.seed([7883D2F6CFCB4981:F0D7ED2C61372479]: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:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1835)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1773)
at org.apache.solr.cloud.api.collections.ShardSplitTest.splitByRouteFieldTest(ShardSplitTest.java:830)
at org.apache.solr.cloud.api.collections.ShardSplitTest.test(ShardSplitTest.java:111)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test
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([7883D2F6CFCB4981]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.update.TestInPlaceUpdatesDistrib
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([7883D2F6CFCB4981]:0)
FAILED: org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([7883D2F6CFCB4981:905F7F143C2353A1]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1023)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:983)
at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:245)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.solr.common.SolrException: Circuit Breakers tripped Memory Circuit Breaker triggered as JVM heap usage values are greater than allocated threshold.Seen JVM heap memory usage 422782680 and allocated threshold 394788864
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:320)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2646)
at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
at org.apache.solr.util.TestHarness.query(TestHarness.java:328)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:997)
... 45 more
Build Log:
[...truncated 13961 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4] 2> 365469 INFO (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 365469 INFO (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 365470 INFO (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/data-dir-22-001
[junit4] 2> 365471 WARN (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 365471 INFO (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 365473 INFO (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-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)
[junit4] 2> 365473 INFO (SUITE-ShardSplitTest-seed#[7883D2F6CFCB4981]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ig_zbo/z
[junit4] 2> 365476 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 365477 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 365477 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 365478 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 365577 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer start zk server on port: 45549
[junit4] 2> 365577 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45549
[junit4] 2> 365577 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45549
[junit4] 2> 365577 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45549
[junit4] 2> 365578 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 365578 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 365581 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 365581 DEBUG (zkConnectionManagerCallback-2431-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d1b1a14 name: ZooKeeperConnection Watcher:127.0.0.1:45549 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 365581 INFO (zkConnectionManagerCallback-2431-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 365581 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 365581 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 365582 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 365583 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 365583 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 365584 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 365584 DEBUG (zkConnectionManagerCallback-2433-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4afe443d name: ZooKeeperConnection Watcher:127.0.0.1:45549/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 365584 INFO (zkConnectionManagerCallback-2433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 365584 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 365585 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 365586 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterprops.json
[junit4] 2> 365586 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 365586 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 365587 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 365587 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 365588 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 365588 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 365589 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 365589 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 365590 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 365590 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 365591 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 365591 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 365592 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 365592 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 365593 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 365593 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 365594 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 365594 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 365595 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 365595 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 365596 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 365596 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 365596 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 365705 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 365705 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 365705 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 365705 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 365705 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 365706 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 365706 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3260284e{/ig_zbo/z,null,AVAILABLE}
[junit4] 2> 365706 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c59d1e8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37417}
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.s.Server Started @365733ms
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/tempDir-001/control/data, replicaType=NRT, hostContext=/ig_zbo/z, hostPort=37417, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/control-001/cores}
[junit4] 2> 365707 ERROR (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 365707 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-17T20:51:07.033Z
[junit4] 2> 365707 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 365708 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 365709 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 365709 DEBUG (zkConnectionManagerCallback-2435-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@12658e5a name: ZooKeeperConnection Watcher:127.0.0.1:45549/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 365709 INFO (zkConnectionManagerCallback-2435-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 365709 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 365710 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 365811 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 365811 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 365811 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/control-001/solr.xml
[junit4] 2> 365814 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 365814 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 365815 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@42e7cc5b, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 366809 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 366810 WARN (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@36a8b55e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 366810 WARN (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@36a8b55e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 366814 WARN (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1641e6d6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 366814 WARN (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1641e6d6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 366815 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45549/solr
[junit4] 2> 366815 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 366815 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 366817 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 366817 DEBUG (zkConnectionManagerCallback-2446-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d62597b name: ZooKeeperConnection Watcher:127.0.0.1:45549 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 366817 INFO (zkConnectionManagerCallback-2446-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 366817 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 366817 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 366918 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 366919 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 366920 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 366920 DEBUG (zkConnectionManagerCallback-2448-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@36545ee6 name: ZooKeeperConnection Watcher:127.0.0.1:45549/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 366920 INFO (zkConnectionManagerCallback-2448-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 366920 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 366921 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 366922 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 366922 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 366923 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/async_ids
[junit4] 2> 366924 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 366925 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 366926 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events
[junit4] 2> 366926 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/triggerState
[junit4] 2> 366927 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeAdded
[junit4] 2> 366928 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeLost
[junit4] 2> 366929 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 366929 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 366930 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /autoscaling.json
[junit4] 2> 366931 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/protwords.txt
[junit4] 2> 366932 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/solrconfig.xml
[junit4] 2> 366933 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/synonyms.txt
[junit4] 2> 366934 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/managed-schema
[junit4] 2> 366935 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hi.txt
[junit4] 2> 366936 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fa.txt
[junit4] 2> 366938 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_es.txt
[junit4] 2> 366939 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_sv.txt
[junit4] 2> 366940 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ca.txt
[junit4] 2> 366941 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_it.txt
[junit4] 2> 366942 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_eu.txt
[junit4] 2> 366943 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_no.txt
[junit4] 2> 366944 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_en.txt
[junit4] 2> 366945 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_et.txt
[junit4] 2> 366948 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ar.txt
[junit4] 2> 366950 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fi.txt
[junit4] 2> 366957 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hu.txt
[junit4] 2> 366958 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/hyphenations_ga.txt
[junit4] 2> 366959 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_nl.txt
[junit4] 2> 366961 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_de.txt
[junit4] 2> 366962 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_th.txt
[junit4] 2> 366963 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stemdict_nl.txt
[junit4] 2> 366964 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ca.txt
[junit4] 2> 366966 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_bg.txt
[junit4] 2> 366967 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_gl.txt
[junit4] 2> 366968 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_da.txt
[junit4] 2> 366969 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_tr.txt
[junit4] 2> 366970 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_fr.txt
[junit4] 2> 366971 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ja.txt
[junit4] 2> 366972 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hy.txt
[junit4] 2> 366974 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stoptags_ja.txt
[junit4] 2> 366975 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_it.txt
[junit4] 2> 366976 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_cz.txt
[junit4] 2> 366977 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_id.txt
[junit4] 2> 366978 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ro.txt
[junit4] 2> 366979 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/userdict_ja.txt
[junit4] 2> 366980 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_el.txt
[junit4] 2> 366982 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ga.txt
[junit4] 2> 366983 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fr.txt
[junit4] 2> 366984 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ga.txt
[junit4] 2> 366985 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_lv.txt
[junit4] 2> 366987 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ru.txt
[junit4] 2> 366988 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_pt.txt
[junit4] 2> 366989 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/stopwords.txt
[junit4] 2> 366990 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 366990 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 366990 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 366991 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 366991 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 366992 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 366993 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 366993 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 366994 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37417_ig_zbo%2Fz
[junit4] 2> 366994 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 366995 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.Overseer Overseer (id=72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) starting
[junit4] 2> 366995 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 366996 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 366997 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 366999 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 366999 INFO (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37417_ig_zbo%2Fz
[junit4] 2> 366999 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37417_ig_zbo%2Fz
[junit4] 2> 367000 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes
[junit4] 2> 367000 INFO (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 367000 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:37417_ig_zbo%2Fz]
[junit4] 2> 367001 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 367001 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 #Completed tasks: 0
[junit4] 2> 367001 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 367001 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 367001 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 367001 INFO (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 367002 DEBUG (OverseerAutoScalingTriggerThread-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.scheduled_maintenance
[junit4] 2> 367002 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 367003 WARN (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] 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> 367004 DEBUG (zkCallback-2447-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 367008 DEBUG (zkCallback-2447-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [127.0.0.1:37417_ig_zbo%2Fz] -> [127.0.0.1:37417_ig_zbo%2Fz]
[junit4] 2> 367009 DEBUG (OverseerAutoScalingTriggerThread-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.auto_add_replicas
[junit4] 2> 367036 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 367055 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42e7cc5b
[junit4] 2> 367064 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42e7cc5b
[junit4] 2> 367064 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42e7cc5b
[junit4] 2> 367065 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/control-001/cores
[junit4] 2> 367088 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 367092 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 367092 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 367096 DEBUG (zkConnectionManagerCallback-2465-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@514b7945 name: ZooKeeperConnection Watcher:127.0.0.1:45549/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 367096 INFO (zkConnectionManagerCallback-2465-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 367096 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 367096 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 367097 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 367098 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 367098 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 367098 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:37417_ig_zbo%2Fz]
[junit4] 2> 367099 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 367099 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 367099 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 367099 INFO (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45549/solr ready
[junit4] 2> 367115 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/collection-queue-work
[junit4] 2> 367115 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/collection-queue-work
[junit4] 2> 367118 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]]
[junit4] 2> 367118 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running
[junit4] 2> 367118 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id: /overseer/collection-queue-work/qn-0000000000 message: {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 367138 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000
[junit4] 2> 367138 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 367138 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 367140 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 #Completed tasks: 0
[junit4] 2> 367140 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 367140 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 367140 DEBUG (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 367140 INFO (OverseerCollectionConfigSetProcessor-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 367144 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=0, not overwriting ZK version.
[junit4] 2> 367144 INFO (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 367145 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/control_collection
[junit4] 2> 367146 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode: control_collection
[junit4] 2> 367146 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists
[junit4] 2> 367147 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 367147 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 367147 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 367148 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/control_collection/state.json
[junit4] 2> 367149 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 367249 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Creating SolrCores for new collection control_collection, shardNames [shard1] , message : {
[junit4] 2> "name":"control_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "createNodeSet":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 367251 DEBUG (OverseerThreadFactory-2455-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Creating core control_collection_shard1_replica_n1 as part of shard shard1 of collection control_collection on 127.0.0.1:37417_ig_zbo%2Fz
[junit4] 2> 367254 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 367254 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 367257 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 367258 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 367258 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 367259 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37417/ig_zbo/z"}
[junit4] 2> 367259 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(control_collection//collections/control_collection/state.json/0 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 367360 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 0
[junit4] 2> 367361 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 368260 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/control_collection]
[junit4] 2> 368268 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 368293 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 368303 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 368386 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 368387 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@42e7cc5b
[junit4] 2> 368387 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.api.collections.ShardSplitTest_7883D2F6CFCB4981-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 368406 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=267434541, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 368418 WARN (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 368477 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 368477 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 368478 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 368478 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 368479 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=11, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=53.583984375, floorSegmentMB=0.66015625, forceMergeDeletesPctAllowed=12.411754500577988, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=49.32701496863206
[junit4] 2> 368480 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Loading collection config from: [/collections/control_collection]
[junit4] 2> 368481 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 368481 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 368482 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 368482 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1689168557960593408
[junit4] 2> 368485 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 368486 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Add data for [control_collection] ver [1]
[junit4] 2> 368486 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 368488 INFO (searcherExecutor-2467-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 368496 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader updated per-replica states changed for: control_collection, ver: 0 , new vals: []
[junit4] 2> 368496 DEBUG (watches-2449-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 368496 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Removing uninteresting collection [control_collection]
[junit4] 2> 368496 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 368498 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/terms/shard1
[junit4] 2> 368500 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/terms/shard1
[junit4] 2> 368500 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 368500 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 368501 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 368502 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 368504 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 368504 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 368504 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37417/ig_zbo/z/control_collection_shard1_replica_n1/
[junit4] 2> 368504 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 368504 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:37417/ig_zbo/z/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 368504 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72841592048254980-core_node2-n_0000000000
[junit4] 2> 368506 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 368506 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37417/ig_zbo/z/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 368507 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 368507 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Add data for [control_collection] ver [1]
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader updated per-replica states changed for: control_collection, ver: 0 , new vals: []
[junit4] 2> 368508 DEBUG (watches-2449-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 368508 DEBUG (watches-2449-thread-1-processing-n:127.0.0.1:37417_ig_zbo%2Fz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 368508 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader CollectionStatePredicate failed for control_collection, cversion : -1
[junit4] 2> 368608 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 1
[junit4] 2> 368610 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json
[junit4] 2> 368611 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json
[junit4] 2> 368611 INFO (zkCallback-2447-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 368611 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 368611 INFO (zkCallback-2447-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 368611 DEBUG (zkCallback-2447-thread-1) [ ] o.a.s.c.c.ZkStateReader Updating data for [control_collection] from [1] to [2]
[junit4] 2> 368611 DEBUG (zkCallback-2447-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 368611 DEBUG (zkCallback-2447-thread-2) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 368611 DEBUG (watches-2449-thread-1) [ ] o.a.s.c.c.ZkStateReader leader found for control_collection/shard1 to be core_node2:{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37417/ig_zbo/z"}
[junit4] 2> 368612 INFO (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 368612 DEBUG (watches-2449-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 368614 DEBUG (TEST-ShardSplitTest.testSplitWithChaosMonkey-seed#[7883D2F6CFCB4981]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 368614 DEBUG (qtp1693194277-4591) [n:127.0.0.1:37417_ig_zbo%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Refreshing lazily-loaded state for collection control_collection
[junit4] 2> 368615 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 368615 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"state"}
[junit4] 2> 368615 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37417_ig_zbo%2Fz",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "base_url":"http://127.0.0.1:37417/ig_zbo/z"}
[junit4] 2> 368615 DEBUG (OverseerStateUpdate-72841592048254980-127.0.0.1:37417_ig_zbo%2Fz-n_0000000000) [n:127.0.0.1:37417_ig_zbo%2Fz ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(control_collection//collections/control_collection/state.json/2 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2>
[...truncated too long message...]
wn Source)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$56/1317065772.run(Unknown Source)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 120) Thread[id=117060, name=zkConnectionManagerCallback-40557-thread-1, state=WAITING, group=TGRP-TestInPlaceUpdatesDistrib]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-PE, timezone=America/Kralendijk
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=141,free=4273488,total=533200896
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrXml, DeleteReplicaTest, AssignBackwardCompatibilityTest, TriggerEventQueueTest, HdfsDirectoryTest, TestPerFieldSimilarityWithDefaultOverride, TestPhraseSuggestions, TestSlowCompositeReaderWrapper, ZkNodePropsTest, TestFieldCacheVsDocValues, AdminHandlersProxyTest, DistributedFacetPivotLongTailTest, TestMergePolicyConfig, TestDistributedMap, TestManagedStopFilterFactory, TestDocBasedVersionConstraints, DocValuesNotIndexedTest, SuggesterWFSTTest, TestQuerySenderNoQuery, TestAtomicUpdateErrorCases, TestCloudPseudoReturnFields, HdfsCollectionsAPIDistributedZkTest, TestHdfsBackupRestoreCore, IndexSchemaRuntimeFieldTest, RankQueryTest, TestSolrFieldCacheBean, TestFieldTypeCollectionResource, PackageManagerCLITest, V2ApiIntegrationTest, GraphQueryTest, DocumentAnalysisRequestHandlerTest, NumericFieldsTest, TestDynamicLoading, TestRTimerTree, TestApiFramework, TestHashPartitioner, HdfsRecoverLeaseTest, SpellCheckComponentTest, SimplePostToolTest, DistributedSpellCheckComponentTest, TestStressUserVersions, DistributedDebugComponentTest, ResourceLoaderTest, SortSpecParsingTest, TestDynamicFieldCollectionResource, TestRecovery, VMParamsZkACLAndCredentialsProvidersTest, TestTolerantUpdateProcessorRandomCloud, LeaderTragicEventTest, CollectionsAPISolrJTest, SynonymTokenizerTest, DistributedMLTComponentTest, FastVectorHighlighterTest, MoveReplicaHDFSTest, TestEmbeddedSolrServerAdminHandler, TestReversedWildcardFilterFactory, CdcrUpdateLogTest, TestSimExtremeIndexing, TestFileDictionaryLookup, ConnectionReuseTest, SignatureUpdateProcessorFactoryTest, TestFastLRUCache, TestScoreJoinQPScore, TestZkChroot, PingRequestHandlerTest, TestTrieFacet, ByteBuffersDirectoryFactoryTest, TestBackupRepositoryFactory, MetricsConfigTest, TestReqParamsAPI, TestSortableTextField, WordBreakSolrSpellCheckerTest, BasicAuthStandaloneTest, HealthCheckHandlerTest, TestManagedResourceStorage, UUIDFieldTest, SolrRrdBackendFactoryTest, PluginInfoTest, HttpTriggerListenerTest, TestRandomCollapseQParserPlugin, TestDistributedSearch, SimpleFacetsTest, BasicFunctionalityTest, HttpPartitionOnCommitTest, TestSimComputePlanAction, RoutingToNodesWithPropertiesTest, MetricsHistoryWithAuthIntegrationTest, ResponseHeaderTest, InfixSuggestersTest, PreAnalyzedUpdateProcessorTest, TestCustomSort, DirectoryFactoriesTest, PropertiesRequestHandlerTest, TestPointFields, TestGeoJSONResponseWriter, PeerSyncWithLeaderAndIndexFingerprintCachingTest, ClassificationUpdateProcessorTest, TestSearchPerf, OverseerCollectionConfigSetProcessorTest, AtomicUpdatesTest, TestSubQueryTransformer, TestCollectionAPI, TestCloudJSONFacetSKG, TestSolrCoreSnapshots, TestExpandComponent, TestInitQParser, DistribDocExpirationUpdateProcessorTest, NestedShardedAtomicUpdateTest, TestCrossCoreJoin, HdfsThreadLeakTest, ChaosMonkeyNothingIsSafeTest, TestManagedSchema, IgnoreLargeDocumentProcessorFactoryTest, TestSolrCoreProperties, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, TestDeprecatedFilters, AddReplicaTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, ConnectionManagerTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest, HttpPartitionWithTlogReplicasTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, OverseerStatusTest, ReindexCollectionTest, ShardRoutingCustomTest, ShardRoutingTest, SolrCloudExampleTest, SyncSliceTest, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudRecovery2, TestCloudSearcherWarming, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDynamicFieldNamesIndexCorrectly, TestOnReconnectListenerSupport, TestPullReplica, TestPullReplicaErrorHandling, TestSolrCloudWithDelegationTokens, TestSolrCloudWithSecureImpersonation, TestStressInPlaceUpdates, TestTlogReplayVsRecovery, TestWaitForStateWithJettyShutdowns, TriLevelCompositeIdRoutingTest, AssignTest, AsyncCallRequestStatusResponseTest, CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest, ConcurrentCreateCollectionTest, ConcurrentDeleteAndCreateCollectionTest, TestLocalFSCloudBackupRestore, TestRequestStatusCollectionAPI, AutoScalingHandlerTest, ExecutePlanActionTest, IndexSizeTriggerTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, RestoreTriggerStateTest, ScheduledTriggerIntegrationTest, SearchRateTriggerIntegrationTest, TestSimDistributedQueue, TestSimLargeCluster, CdcrVersionReplicationTest, CdcrWithNodesRestartsTest, TestClusterStateMutator, ZkCollectionPropsCachingTest, ZkStateReaderTest, ZkStateWriterTest, ImplicitSnitchTest, RulesTest, ZkDynamicConfigTest, AlternateDirectoryTest, ExitableDirectoryReaderTest, TestConfig, TestConfigOverlay, TestConfigSetImmutable, TestCoreContainer, TestImplicitCoreProperties, TestInfoStreamLogging, TestInitParams, TestSimpleTextCodec, TestSolrDeletionPolicy1, TestSolrDeletionPolicy2, SearchHandlerTest, TestBlobHandler, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, LukeRequestHandlerTest, SegmentsInfoRequestHandlerTest, StatsReloadRaceTest, TestConfigsApi, TestCoreAdminApis, ThreadDumpHandlerTest, ZookeeperReadAPITest, BadComponentTest, CloudReplicaSourceTest, CustomHighlightComponentTest, QueryElevationComponentTest, SuggestComponentTest, TermVectorComponentTest, TestPivotHelperCode, TestTrackingShardHandlerFactory, TestExportWriter, HighlighterWithoutStoredIdTest, WrapperMergePolicyFactoryTest, TestLegacyFieldReuse, TestLegacyTerms, TestNumericRangeQuery32, TestNumericTokenStream, SolrCoreMetricManagerTest, SolrMetricsIntegrationTest, SolrJmxReporterCloudTest, SolrJmxReporterTest, SolrCloudReportersTest, TestFaceting, TestIntervalFaceting, EnumFieldTest, PreAnalyzedFieldManagedSchemaCloudTest, RequiredFieldsTest, SchemaApiFailureTest, TestCloudManagedSchema, TestCollationField, TestCollationFieldDocValues, TestManagedSchemaThreadSafety, TestSchemaManager, TestUseDocValuesAsStored, MaxScoreCollectorTest, MergeStrategyTest, TestCaffeineCache, TestElisionMultitermQuery, TestFiltering, TestNoOpRegenerator, TestPayloadCheckQParserPlugin, TestPayloadScoreQParserPlugin, TestQueryTypes, TestReRankQParserPlugin, TestSimpleQParserPlugin, TestSmileRequest, TestSolr4Spatial, TestSolrQueryParser, TestStandardQParsers, TestStressLucene, TestStressRecovery, TestXmlQParser, TestXmlQParserPlugin, RangeFacetCloudTest, TestJsonFacetErrors, TestJsonFacetRefinement, TestJsonFacets, AuditLoggerPluginTest, JWTAuthPluginTest, JWTVerificationkeyResolverTest, MultiDestinationAuditLoggerTest, PKIAuthenticationIntegrationTest, TestExternalRoleRuleBasedAuthorizationPlugin, TestPKIAuthenticationPlugin, TestSha256AuthenticationProvider, TestDelegationWithHadoopAuth, TestImpersonationWithHadoopAuth, TestZkAclsWithHadoopAuth, DirectSolrConnectionTest, ConjunctionSolrSpellCheckerTest, DirectSolrSpellCheckerTest, SpellCheckCollatorTest, SpellPossibilityIteratorTest, SuggesterTSTTest, BlockDirectoryTest, TestFieldCache, TestFieldCacheReopen, TestLegacyFieldCache, TestNumericTerms32, TestUninvertingReader, AddBlockUpdateTest, DirectUpdateHandlerTest, DocumentBuilderTest, MaxSizeAutoCommitTest, PeerSyncTest, SolrCmdDistributorTest, SolrIndexConfigTest, SolrIndexMetricsTest, TestHdfsUpdateLog, TestInPlaceUpdateWithRouteField, TestInPlaceUpdatesDistrib]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestInPlaceUpdatesDistrib -Dtests.seed=7883D2F6CFCB4981 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/test-data/enwiki.random.lines.txt -Dtests.locale=es-PE -Dtests.timezone=America/Kralendijk -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestInPlaceUpdatesDistrib (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([7883D2F6CFCB4981]:0)
[junit4] Completed [923/923 (3!)] on J1 in 7221.22s, 1 test, 2 errors <<< FAILURES!
[...truncated 50720 lines...]
[JENKINS] Lucene » Lucene-Solr-NightlyTests-8.x - Build # 157 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-NightlyTests-8.x/157/
1 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testNodeAdded
Error Message:
Action was not fired till 30 seconds
Stack Trace:
java.lang.AssertionError: Action was not fired till 30 seconds
at __randomizedtesting.SeedInfo.seed([6298FBE0147E2382:75BAD97B6DD8B81]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.cloud.TestWithCollection.testNodeAdded(TestWithCollection.java:471)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14632 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> 2396128 INFO (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2396129 INFO (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001/data-dir-125-001
[junit4] 2> 2396129 WARN (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=52 numCloses=52
[junit4] 2> 2396129 INFO (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2396131 INFO (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2396131 INFO (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2396131 INFO (SUITE-TestWithCollection-seed#[6298FBE0147E2382]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 2396135 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddReplicaWithPolicy
[junit4] 2> 2396135 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001/tempDir-001
[junit4] 2> 2396136 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2396136 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2396136 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2396137 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 2396236 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer start zk server on port: 44129
[junit4] 2> 2396236 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:44129
[junit4] 2> 2396236 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44129
[junit4] 2> 2396236 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44129
[junit4] 2> 2396242 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2396256 INFO (zkConnectionManagerCallback-17102-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2396256 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2396336 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2396338 INFO (zkConnectionManagerCallback-17104-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2396338 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2396341 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2396459 INFO (zkConnectionManagerCallback-17106-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2396459 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2396477 WARN (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]-SendThread(127.0.0.1:44129)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce10e7ba0002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce10e7ba0002, 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 0x102ce10e7ba0002, 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> 2396639 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2396639 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2396639 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 2396648 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2396648 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2396649 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 2396910 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2396910 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2396910 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2396910 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e45039b{/solr,null,AVAILABLE}
[junit4] 2> 2396945 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2396945 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2396945 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2396947 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7907bbd3{/solr,null,AVAILABLE}
[junit4] 2> 2396948 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@631cf566{SSL, (ssl, http/1.1)}{127.0.0.1:33437}
[junit4] 2> 2396948 INFO (jetty-launcher-17107-thread-1) [ ] o.e.j.s.Server Started @2396979ms
[junit4] 2> 2396948 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:44129/solr, hostPort=33437}
[junit4] 2> 2396948 ERROR (jetty-launcher-17107-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2396948 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2396948 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 2396948 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2396949 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 2396949 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T20:15:56.337Z
[junit4] 2> 2396955 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2396955 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3587d2e0{SSL, (ssl, http/1.1)}{127.0.0.1:39945}
[junit4] 2> 2396955 INFO (jetty-launcher-17107-thread-2) [ ] o.e.j.s.Server Started @2396986ms
[junit4] 2> 2396955 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:44129/solr, hostPort=39945}
[junit4] 2> 2396956 ERROR (jetty-launcher-17107-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2396956 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2396956 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 2396956 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2396956 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr
[junit4] 2> 2396956 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T20:15:56.344Z
[junit4] 2> 2396970 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2396973 INFO (zkConnectionManagerCallback-17109-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2396973 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2396977 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2396981 INFO (zkConnectionManagerCallback-17111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2396981 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2396984 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2396987 WARN (jetty-launcher-17107-thread-1-SendThread(127.0.0.1:44129)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce10e7ba0003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce10e7ba0003, 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 0x102ce10e7ba0003, 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> 2397931 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2397931 WARN (jetty-launcher-17107-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2397946 WARN (jetty-launcher-17107-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@49244048[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2397947 WARN (jetty-launcher-17107-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@49244048[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2398100 WARN (jetty-launcher-17107-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2398105 WARN (jetty-launcher-17107-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@76058a09[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2398105 WARN (jetty-launcher-17107-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@76058a09[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2398106 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44129/solr
[junit4] 2> 2398130 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2398134 INFO (zkConnectionManagerCallback-17125-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2398134 INFO (jetty-launcher-17107-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2398136 WARN (jetty-launcher-17107-thread-1-SendThread(127.0.0.1:44129)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102ce10e7ba0005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102ce10e7ba0005, 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 0x102ce10e7ba0005, 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> 2398291 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2398295 INFO (zkConnectionManagerCallback-17127-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2398296 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2399433 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33437_solr
[junit4] 2> 2399436 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.c.Overseer Overseer (id=72847115993874438-127.0.0.1:33437_solr-n_0000000000) starting
[junit4] 2> 2399474 INFO (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33437_solr
[junit4] 2> 2399474 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33437_solr
[junit4] 2> 2399476 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2399486 INFO (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2399489 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 2399490 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 2399490 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2399504 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2399505 WARN (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_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> 2399545 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:33437_solr]
[junit4] 2> 2399559 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 2399576 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 2399580 DEBUG (ScheduledTrigger-17132-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> 2399580 TRACE (ScheduledTrigger-17132-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:33437_solr]
[junit4] 2> 2399580 TRACE (ScheduledTrigger-17132-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:33437_solr]
[junit4] 2> 2399766 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2399831 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33437.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2399843 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33437.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2399843 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33437.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2399845 INFO (jetty-launcher-17107-thread-1) [n:127.0.0.1:33437_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001/tempDir-001/node1
[junit4] 2> 2400614 DEBUG (ScheduledTrigger-17132-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 2400614 TRACE (ScheduledTrigger-17132-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:33437_solr]
[junit4] 2> 2400614 TRACE (ScheduledTrigger-17132-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:33437_solr]
[junit4] 2> 2401615 DEBUG (ScheduledTrigger-17132-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 2401615 TRACE (ScheduledTrigger-17132-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:33437_solr]
[junit4] 2> 2401615 TRACE (ScheduledTrigger-17132-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:33437_solr]
[junit4] 2> 2401949 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2401949 WARN (jetty-launcher-17107-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2401966 WARN (jetty-launcher-17107-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1df00ea8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2401966 WARN (jetty-launcher-17107-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1df00ea8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2401970 WARN (jetty-launcher-17107-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 2401979 WARN (jetty-launcher-17107-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4868c675[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2401979 WARN (jetty-launcher-17107-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4868c675[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2401981 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44129/solr
[junit4] 2> 2401987 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2402003 INFO (zkConnectionManagerCallback-17147-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2402004 INFO (jetty-launcher-17107-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2402109 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2402113 INFO (zkConnectionManagerCallback-17149-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2402113 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2402128 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2402145 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39945_solr as DOWN
[junit4] 2> 2402149 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2402149 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39945_solr
[junit4] 2> 2402153 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:39945_solr
[junit4] 2> 2402154 INFO (zkCallback-17126-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2402156 INFO (zkCallback-17148-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2402163 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2402164 WARN (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_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> 2402195 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2402250 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39945.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2402266 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39945.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2402266 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39945.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2402267 INFO (jetty-launcher-17107-thread-2) [n:127.0.0.1:39945_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001/tempDir-001/node2
[junit4] 2> 2402494 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 2402499 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2402500 INFO (zkConnectionManagerCallback-17162-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2402500 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2402502 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2402504 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44129/solr ready
[junit4] 2> 2402510 DEBUG (zkCallback-17126-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 2402510 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2402524 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 2402527 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2402626 INFO (qtp1776590358-40936) [n:127.0.0.1:33437_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2402626 INFO (qtp1776590358-40936) [n:127.0.0.1:33437_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 2402631 INFO (qtp1065335184-40947) [n:127.0.0.1:39945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 2402631 DEBUG (qtp1065335184-40944) [n:127.0.0.1:39945_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2402632 INFO (qtp1065335184-40944) [n:127.0.0.1:39945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=45
[junit4] 2> 2402632 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[6298FBE0147E2382]) [ ] o.a.s.c.TestWithCollection Chosen node 127.0.0.1:33437_solr for collection testAddReplicaWithPolicy_abc
[junit4] 2> 2402633 DEBUG (zkCallback-17126-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 2402633 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2402633 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 2402638 DEBUG (OverseerAutoScalingTriggerThread-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2402645 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAddReplicaWithPolicy_abc
[junit4] 2> 2402646 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 2402652 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/protwords.txt
[junit4] 2> 2402655 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 2402658 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 2402666 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 2402672 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 2402676 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 2402680 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 2402685 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 2402689 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 2402693 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 2402696 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 2402707 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 2402711 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 2402714 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 2402721 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 2402727 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 2402741 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 2402747 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 2402753 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 2402758 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 2402762 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 2402767 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 2402771 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 2402773 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 2402777 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 2402785 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 2402791 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 2402794 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 2402797 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 2402801 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 2402807 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 2402812 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 2402819 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 2402822 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 2402825 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 2402830 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 2402839 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 2402846 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 2402849 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 2402852 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 2402855 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 2402859 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 2402861 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 2402863 INFO (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 2402870 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testAddReplicaWithPolicy_abc
[junit4] 2> 2402871 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testAddReplicaWithPolicy_abc/state.json
[junit4] 2> 2403093 DEBUG (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 2403106 INFO (qtp1065335184-40946) [n:127.0.0.1:39945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 2403109 INFO (qtp1776590358-40935) [n:127.0.0.1:33437_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 2403110 DEBUG (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper New session created, 12047155958590317
[junit4] 2> 2403115 DEBUG (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession 12047155958590317
[junit4] 2> 2403124 INFO (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testAddReplicaWithPolicy_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2403125 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 2403125 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2403226 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 0
[junit4] 2> 2403328 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplicaWithPolicy_abc.AUTOCREATED&newCollection=true&name=testAddReplicaWithPolicy_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplicaWithPolicy_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2403329 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 2403332 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplicaWithPolicy_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 2403332 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:33437/solr"}
[junit4] 2> 2403332 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplicaWithPolicy_abc//collections/testAddReplicaWithPolicy_abc/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 2403340 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2403359 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2403415 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2403433 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 1
[junit4] 2> 2403483 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testAddReplicaWithPolicy_abc_shard1_replica_n1' using configuration from configset testAddReplicaWithPolicy_abc.AUTOCREATED, trusted=true
[junit4] 2> 2403484 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33437.solr.core.testAddReplicaWithPolicy_abc.shard1.replica_n1' (registry 'solr.core.testAddReplicaWithPolicy_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@40d55b22
[junit4] 2> 2403484 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[testAddReplicaWithPolicy_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001/tempDir-001/node1/testAddReplicaWithPolicy_abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001/tempDir-001/node1/testAddReplicaWithPolicy_abc_shard1_replica_n1/data/]
[junit4] 2> 2403546 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2403547 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2403548 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 2403548 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2403551 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 2403551 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 2403551 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 2403552 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2403552 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2403552 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 2403553 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2403554 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1689256945717870592
[junit4] 2> 2403556 INFO (searcherExecutor-17164-thread-1-processing-n:127.0.0.1:33437_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2403556 INFO (searcherExecutor-17164-thread-1-processing-n:127.0.0.1:33437_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2403556 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 2403558 INFO (searcherExecutor-17164-thread-1-processing-n:127.0.0.1:33437_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [testAddReplicaWithPolicy_abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2403559 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testAddReplicaWithPolicy_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2403560 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testAddReplicaWithPolicy_abc/leaders/shard1
[junit4] 2> 2403564 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2403564 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2403564 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33437/solr/testAddReplicaWithPolicy_abc_shard1_replica_n1/
[junit4] 2> 2403564 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2403565 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:33437/solr/testAddReplicaWithPolicy_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 2403565 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testAddReplicaWithPolicy_abc/leaders/shard1/leader after winning as /collections/testAddReplicaWithPolicy_abc/leader_elect/shard1/election/72847115993874438-core_node2-n_0000000000
[junit4] 2> 2403567 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33437/solr/testAddReplicaWithPolicy_abc_shard1_replica_n1/ shard1
[junit4] 2> 2403567 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 2403668 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 2
[junit4] 2> 2403669 INFO (zkCallback-17126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2403670 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2403672 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplicaWithPolicy_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 2403673 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true",
[junit4] 2> "base_url":"https://127.0.0.1:33437/solr"}
[junit4] 2> 2403673 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplicaWithPolicy_abc//collections/testAddReplicaWithPolicy_abc/state.json/3 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 2403672 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 2403674 INFO (qtp1776590358-40937) [n:127.0.0.1:33437_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplicaWithPolicy_abc.AUTOCREATED&newCollection=true&name=testAddReplicaWithPolicy_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplicaWithPolicy_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=347
[junit4] 2> 2403675 DEBUG (OverseerThreadFactory-17134-thread-1-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper final release, session 12047155958590317 lived a total of 565ms,
[junit4] 2> 2403678 INFO (qtp1065335184-40944) [n:127.0.0.1:39945_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> 2403678 INFO (qtp1065335184-40944) [n:127.0.0.1:39945_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 2403773 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 3
[junit4] 2> 2403774 INFO (zkCallback-17148-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2403774 INFO (zkCallback-17126-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2403774 INFO (zkCallback-17126-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2403775 INFO (qtp1065335184-40944) [n:127.0.0.1:39945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testAddReplicaWithPolicy_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:33437_solr&wt=javabin&version=2} status=0 QTime=1142
[junit4] 2> 2403780 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAddReplicaWithPolicy_xyz
[junit4] 2> 2403780 INFO (OverseerCollectionConfigSetProcessor-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_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> 2403781 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED
[junit4] 2> 2403783 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 2403784 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema
[junit4] 2> 2403788 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 2403793 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 2403796 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 2403798 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 2403802 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 2403803 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 2403807 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 2403808 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 2403810 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 2403829 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 2403832 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 2403835 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 2403837 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 2403839 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 2403840 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 2403843 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 2403857 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 2403861 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 2403863 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 2403867 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 2403872 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 2403879 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 2403885 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 2403887 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 2403889 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 2403892 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 2403903 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 2403905 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 2403907 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 2403908 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 2403909 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 2403911 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 2403912 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 2403914 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 2403915 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 2403918 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 2403920 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 2403925 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 2403926 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 2403927 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 2403928 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/synonyms.txt
[junit4] 2> 2403929 INFO (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/stopwords.txt
[junit4] 2> 2403934 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testAddReplicaWithPolicy_xyz
[junit4] 2> 2404034 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testAddReplicaWithPolicy_xyz/state.json
[junit4] 2> 2404136 DEBUG (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 2404140 INFO (qtp1065335184-40943) [n:127.0.0.1:39945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 2404141 INFO (qtp1776590358-40936) [n:127.0.0.1:33437_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testAddReplicaWithPolicy_abc.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 2404142 INFO (qtp1776590358-40940) [n:127.0.0.1:33437_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 2404143 DEBUG (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper New session created, 12047156991703196
[junit4] 2> 2404145 DEBUG (OverseerThreadFactory-17134-thread-2-processing-n:127.0.0.1:33437_solr) [n:127.0.0.1:33437_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession 12047156991703196
[junit4] 2> 2404148 INFO (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testAddReplicaWithPolicy_xyz",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testAddReplicaWithPolicy_xyz_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2404149 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 2404149 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_xyz_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33437_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 2404249 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_xyz/state.json version: 0
[junit4] 2> 2404351 INFO (qtp1776590358-40934) [n:127.0.0.1:33437_solr x:testAddReplicaWithPolicy_xyz_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplicaWithPolicy_xyz.AUTOCREATED&newCollection=true&name=testAddReplicaWithPolicy_xyz_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplicaWithPolicy_xyz&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2404352 INFO (qtp1776590358-40934) [n:127.0.0.1:33437_solr c:testAddReplicaWithPolicy_xyz s:shard1 r:core_node2 x:testAddReplicaWithPolicy_xyz_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 2404355 DEBUG (OverseerStateUpdate-72847115993874438-127.0.0.1:33437_solr-n_0000000000) [n:127.0.0.1:33437_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplicaWithPolicy_xyz_shard1_repli
[...truncated too long message...]
/com.codahale.metrics.MetricRegistry@1c11cae4
[junit4] 2> 2556814 INFO (coreCloseExecutor-18077-thread-1) [n:127.0.0.1:34797_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2556818 INFO (zkCallback-18042-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2556826 INFO (coreCloseExecutor-18077-thread-2) [n:127.0.0.1:34797_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testCreateCollection_xyz.shard1.leader tag=SolrCore@2ad9456f
[junit4] 2> 2556827 INFO (coreCloseExecutor-18077-thread-2) [n:127.0.0.1:34797_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2556829 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2556829 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6033a401: rootName = solr_34797, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@376ea56f
[junit4] 2> 2556834 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2556834 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2178861: rootName = solr_34797, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@6c4784ad
[junit4] 2> 2556836 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2556836 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@65523446: rootName = solr_34797, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@2c6b429
[junit4] 2> 2556836 INFO (jetty-closer-18069-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 2556908 INFO (jetty-closer-18069-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72847126305636358-127.0.0.1:40827_solr-n_0000000000) closing
[junit4] 2> 2556910 INFO (jetty-closer-18069-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@36873452{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 2556910 INFO (jetty-closer-18069-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6c76ed4c{/solr,null,STOPPED}
[junit4] 2> 2556910 INFO (jetty-closer-18069-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2557009 INFO (jetty-closer-18069-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4105abbc{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 2557009 INFO (jetty-closer-18069-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@62c30b8e{/solr,null,STOPPED}
[junit4] 2> 2557010 INFO (jetty-closer-18069-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2557010 INFO (TEST-TestWithCollection.testCreateCollection-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2557213 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> 3 /solr/aliases.json
[junit4] 2> 2 /solr/collections/testCreateCollection_abc/terms/shard1
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/collections/testCreateCollection_xyz/terms/shard1
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/collections/testCreateCollection_abc/state.json
[junit4] 2> 9 /solr/collections/testCreateCollection_xyz/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 9 /solr/collections
[junit4] 2> 9 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 5 /solr/collections/testCreateCollection_abc/state.json
[junit4] 2> 4 /solr/collections/testCreateCollection_xyz/state.json
[junit4] 2>
[junit4] 2> 2557218 INFO (TEST-TestWithCollection.testCreateCollection-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45189
[junit4] 2> 2557218 INFO (TEST-TestWithCollection.testCreateCollection-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45189
[junit4] 2> 2557218 INFO (TEST-TestWithCollection.testCreateCollection-seed#[6298FBE0147E2382]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45189
[junit4] 2> 2557219 INFO (TEST-TestWithCollection.testCreateCollection-seed#[6298FBE0147E2382]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollection
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-NightlyTests-8.x/checkout/solr/build/solr-core/test/J3/temp/solr.cloud.TestWithCollection_6298FBE0147E2382-001
[junit4] 2> Jan 18, 2021 8:18:36 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), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=bg-BG, timezone=Etc/GMT+9
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=89725824,total=519045120
[junit4] 2> NOTE: All tests run in this JVM: [PeerSyncWithBufferUpdatesTest, DistributedFacetExistsSmallTest, BinaryUpdateRequestHandlerTest, TestSolrQueryParser, TestJsonFacetsWithNestedObjects, SuggestComponentContextFilterQueryTest, HdfsUnloadDistributedZkTest, ExecutePlanActionTest, TestReRankQParserPlugin, FullHLLTest, PhrasesIdentificationComponentTest, TestCodecSupport, SearchHandlerTest, DistributedQueueTest, TestNestedDocsSort, SimpleCollectionCreateDeleteTest, TestHdfsCloudBackupRestore, WrapperMergePolicyFactoryTest, RuleEngineTest, TestQueryUtils, TestUnifiedSolrHighlighter, CdcrReplicationHandlerTest, BasicAuthIntegrationTest, TestSimLargeCluster, ReplaceNodeNoTargetTest, UUIDUpdateProcessorFallbackTest, DistanceUnitsTest, TestPayloadCheckQParserPlugin, TestCSVResponseWriter, PKIAuthenticationIntegrationTest, DistributedFacetPivotSmallTest, TestPKIAuthenticationPlugin, TestMissingGroups, SpellingQueryConverterTest, AutoAddReplicasIntegrationTest, XmlUpdateRequestHandlerTest, HDFSCollectionsAPITest, ScheduledTriggerTest, TestDeprecatedFilters, TestContainerPlugin, HdfsChaosMonkeySafeLeaderTest, TestRandomFaceting, TestSimDistributedQueue, TestLRUCache, TestAddFieldRealTimeGet, TestFieldCacheSort, TestManagedSchemaThreadSafety, ZkSolrClientTest, BlockJoinFacetSimpleTest, TestLegacyField, TestRandomFlRTGCloud, BigEndianAscendingWordDeserializerTest, HttpSolrCallGetCoreTest, ConnectionManagerTest, TestConfigSetsAPIExclusivity, TestFieldCollectionResource, EnumFieldTest, QueryParsingTest, SaslZkACLProviderTest, PolyFieldTest, ZookeeperStatusHandlerTest, TestConfigSetProperties, TestDocTermOrds, OrderedExecutorTest, FieldMutatingUpdateProcessorTest, SparseHLLTest, TriLevelCompositeIdRoutingTest, TestReplicationHandlerBackup, TestManagedSynonymGraphFilterFactory, ConcurrentDeleteAndCreateCollectionTest, TestCloudRecovery2, TestLegacyFieldCache, TestFacetMethods, TestSchemaNameResource, ConfigureRecoveryStrategyTest, BlockDirectoryTest, CollectionPropsTest, SpatialHeatmapFacetsTest, TestEmbeddedSolrServerSchemaAPI, RollingRestartTest, TestSolrDeletionPolicy2, TestPivotHelperCode, TestFilteredDocIdSet, SolrPluginUtilsTest, TestTrie, ChangedSchemaMergeTest, TestBulkSchemaConcurrent, TestWaitForStateWithJettyShutdowns, TestStreamBody, SegmentsInfoRequestHandlerTest, TestMaxTokenLenTokenizer, TestInPlaceUpdatesStandalone, DirectUpdateHandlerTest, CollectionTooManyReplicasTest, AuthToolTest, CoreAdminCreateDiscoverTest, HttpPartitionWithTlogReplicasTest, TestXmlQParser, SolrRequestParserTest, TestElisionMultitermQuery, TestJavabinTupleStreamParser, LeaderFailoverAfterPartitionTest, NestedAtomicUpdateTest, SignificantTermsQParserPluginTest, TestSchemaManager, TestAnalyzedSuggestions, SolrXmlInZkTest, TestFuzzyAnalyzedSuggestions, TokenizerChainTest, InfoHandlerTest, DeleteStatusTest, RAMDirectoryFactoryTest, SolrIndexMetricsTest, TestFunctionQuery, TestSolrCLIRunExample, ChaosMonkeySafeLeaderTest, CloudExitableDirectoryReaderTest, ConcurrentCreateRoutedAliasTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteShardTest, ForceLeaderWithTlogReplicasTest, FullSolrCloudDistribCmdsTest, HttpPartitionTest, MultiThreadedOCPTest, RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, ShardRoutingCustomTest, ShardTermsTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, TestAuthenticationFramework, TestCloudPhrasesIdentificationComponent, TestCloudPivotFacet, TestDeleteCollectionOnDownNodes, TestDynamicFieldNamesIndexCorrectly, TestLeaderElectionZkExpiry, TestPullReplica, TestPullReplicaErrorHandling, TestWithCollection]
[junit4] Completed [473/923 (1!)] on J3 in 162.73s, 9 tests, 1 failure <<< FAILURES!
[...truncated 52239 lines...]