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/05/31 08:54:42 UTC
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 294 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/294/
1 tests failed.
FAILED: org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitMixedReplicaTypesLink
Error Message:
Error from server at http://127.0.0.1:33785/ghq: ADDREPLICA failed to create replica
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33785/ghq: ADDREPLICA failed to create replica
at __randomizedtesting.SeedInfo.seed([740D5BA9724EC413:486392F0D7966F8A]: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.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.cloud.api.collections.ShardSplitTest.doSplitMixedReplicaTypes(ShardSplitTest.java:379)
at org.apache.solr.cloud.api.collections.ShardSplitTest.testSplitMixedReplicaTypesLink(ShardSplitTest.java:356)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14975 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4] 2> 1219922 INFO (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-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-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1219923 INFO (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/data-dir-116-001
[junit4] 2> 1219923 WARN (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=142 numCloses=142
[junit4] 2> 1219923 INFO (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1219925 INFO (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-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> 1219925 INFO (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1219925 INFO (SUITE-ShardSplitTest-seed#[740D5BA9724EC413]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ghq/
[junit4] 2> 1219929 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1219929 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1219929 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1219930 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1220029 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer start zk server on port: 39181
[junit4] 2> 1220029 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:39181
[junit4] 2> 1220029 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39181
[junit4] 2> 1220029 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39181
[junit4] 2> 1220031 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1220054 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1220056 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 1220058 DEBUG (zkConnectionManagerCallback-12121-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56b70eed name: ZooKeeperConnection Watcher:127.0.0.1:39181 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 1220058 INFO (zkConnectionManagerCallback-12121-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1220058 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1220058 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1220062 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1220064 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1220074 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1220079 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 1220082 DEBUG (zkConnectionManagerCallback-12123-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3b2c1925 name: ZooKeeperConnection Watcher:127.0.0.1:39181/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 1220082 INFO (zkConnectionManagerCallback-12123-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1220082 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1220082 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1220083 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterprops.json
[junit4] 2> 1220083 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1220083 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1220084 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 1220084 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1220085 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1220085 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1220086 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1220086 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1220087 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1220087 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1220088 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1220088 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1220089 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1220089 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1220090 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1220090 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1220090 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1220090 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1220091 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1220091 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1220092 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1220092 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1220093 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1220220 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1220220 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1220220 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1220225 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1220225 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1220225 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1220404 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@21ba2038{/ghq,null,AVAILABLE}
[junit4] 2> 1220406 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@27e17795{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46023}
[junit4] 2> 1220406 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.s.Server Started @1220434ms
[junit4] 2> 1220406 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/tempDir-001/control/data, replicaType=NRT, hostContext=/ghq, hostPort=46023, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/control-001/cores}
[junit4] 2> 1220407 ERROR (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1220407 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1220407 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.9.0
[junit4] 2> 1220407 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1220407 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1220407 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-05-31T08:13:05.179Z
[junit4] 2> 1220407 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1220412 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1220412 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 1220412 DEBUG (zkConnectionManagerCallback-12125-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2e787f37 name: ZooKeeperConnection Watcher:127.0.0.1:39181/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 1220412 INFO (zkConnectionManagerCallback-12125-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1220412 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1220413 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1220413 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-SendThread(127.0.0.1:39181)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1057868ea750002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1057868ea750002, 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 0x1057868ea750002, 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> 1220514 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1220514 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/control-001/solr.xml
[junit4] 2> 1220517 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1220517 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1220514 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1220519 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@33ba995e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1220768 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1220786 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@466ea5cb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1220786 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@466ea5cb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1220793 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b2d39b6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1220793 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b2d39b6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1220794 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39181/solr
[junit4] 2> 1220794 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1220796 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1220796 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 1220796 DEBUG (zkConnectionManagerCallback-12136-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62d58cd4 name: ZooKeeperConnection Watcher:127.0.0.1:39181 got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 1220796 INFO (zkConnectionManagerCallback-12136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1220796 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1220796 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1220797 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-SendThread(127.0.0.1:39181)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1057868ea750003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1057868ea750003, 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 0x1057868ea750003, 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> 1220897 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1220899 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1220899 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 1220899 DEBUG (zkConnectionManagerCallback-12138-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@48761414 name: ZooKeeperConnection Watcher:127.0.0.1:39181/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 1220899 INFO (zkConnectionManagerCallback-12138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1220899 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1220900 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1220901 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1220901 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1220902 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/async_ids
[junit4] 2> 1220903 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1220903 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1220904 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events
[junit4] 2> 1220905 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/triggerState
[junit4] 2> 1220905 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeAdded
[junit4] 2> 1220906 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/nodeLost
[junit4] 2> 1220907 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1220907 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /autoscaling.json
[junit4] 2> 1220908 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1220908 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/protwords.txt
[junit4] 2> 1220909 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/solrconfig.xml
[junit4] 2> 1220911 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/synonyms.txt
[junit4] 2> 1220912 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/managed-schema
[junit4] 2> 1220913 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hi.txt
[junit4] 2> 1220914 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fa.txt
[junit4] 2> 1220915 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_es.txt
[junit4] 2> 1220916 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_sv.txt
[junit4] 2> 1220917 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ca.txt
[junit4] 2> 1220918 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_it.txt
[junit4] 2> 1220919 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_eu.txt
[junit4] 2> 1220920 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_no.txt
[junit4] 2> 1220921 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_en.txt
[junit4] 2> 1220922 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_et.txt
[junit4] 2> 1220924 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ar.txt
[junit4] 2> 1220926 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fi.txt
[junit4] 2> 1220932 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hu.txt
[junit4] 2> 1220934 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/hyphenations_ga.txt
[junit4] 2> 1220939 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_nl.txt
[junit4] 2> 1220940 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_de.txt
[junit4] 2> 1220941 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_th.txt
[junit4] 2> 1220943 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stemdict_nl.txt
[junit4] 2> 1220944 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ca.txt
[junit4] 2> 1220945 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_bg.txt
[junit4] 2> 1220946 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_gl.txt
[junit4] 2> 1220947 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_da.txt
[junit4] 2> 1220948 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_tr.txt
[junit4] 2> 1220949 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_fr.txt
[junit4] 2> 1220950 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ja.txt
[junit4] 2> 1220952 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_hy.txt
[junit4] 2> 1220953 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stoptags_ja.txt
[junit4] 2> 1220954 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_it.txt
[junit4] 2> 1220955 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_cz.txt
[junit4] 2> 1220956 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_id.txt
[junit4] 2> 1220957 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ro.txt
[junit4] 2> 1220959 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/userdict_ja.txt
[junit4] 2> 1220960 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_el.txt
[junit4] 2> 1220961 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ga.txt
[junit4] 2> 1220962 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_fr.txt
[junit4] 2> 1220963 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/contractions_ga.txt
[junit4] 2> 1220964 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_lv.txt
[junit4] 2> 1220965 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_ru.txt
[junit4] 2> 1220966 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/lang/stopwords_pt.txt
[junit4] 2> 1220967 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /configs/_default/stopwords.txt
[junit4] 2> 1220968 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1220969 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1220969 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 1220969 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 1220970 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 1220970 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 1220970 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 1220972 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1220972 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1220973 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46023_ghq
[junit4] 2> 1220973 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1220973 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.Overseer Overseer (id=73597360926949380-127.0.0.1:46023_ghq-n_0000000000) starting
[junit4] 2> 1220974 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1220975 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1220976 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1220983 INFO (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46023_ghq
[junit4] 2> 1220983 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1220984 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> []
[junit4] 2> 1220984 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 1220985 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46023_ghq
[junit4] 2> 1220986 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 0 #Completed tasks: 0
[junit4] 2> 1220986 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor RunningTasks: []
[junit4] 2> 1220986 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 1220986 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 1220986 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes
[junit4] 2> 1220986 INFO (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: []
[junit4] 2> 1220988 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1220988 WARN (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] 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> 1220993 DEBUG (zkCallback-12137-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: [0])
[junit4] 2> 1220994 INFO (zkCallback-12137-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1220994 DEBUG (zkCallback-12137-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:46023_ghq]
[junit4] 2> 1221001 DEBUG (OverseerAutoScalingTriggerThread-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.scheduled_maintenance
[junit4] 2> 1221008 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1221018 DEBUG (OverseerAutoScalingTriggerThread-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [ ] o.a.s.c.c.SolrZkClient makePath: /autoscaling/events/.auto_add_replicas
[junit4] 2> 1221029 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@33ba995e
[junit4] 2> 1221038 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@33ba995e
[junit4] 2> 1221038 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@33ba995e
[junit4] 2> 1221039 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [n:127.0.0.1:46023_ghq ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/control-001/cores
[junit4] 2> 1221051 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1221052 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1221053 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 1221054 DEBUG (zkConnectionManagerCallback-12155-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c4075d9 name: ZooKeeperConnection Watcher:127.0.0.1:39181/solr got event WatchedEvent state:SyncConnected type:None path:null path: null type: None
[junit4] 2> 1221054 INFO (zkConnectionManagerCallback-12155-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1221054 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1221054 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1221054 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1221055 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {legacyCloud=true}
[junit4] 2> 1221055 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1221055 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... [] -> [127.0.0.1:46023_ghq]
[junit4] 2> 1221056 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 1221056 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 1221056 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=-1, new definition is: Aliases{collectionAliases={}, collectionAliasProperties={}, zNodeVersion=0}
[junit4] 2> 1221056 INFO (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39181/solr ready
[junit4] 2> 1221062 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-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> 1221063 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-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> 1221063 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Got 1 tasks from work-queue : [[org.apache.solr.cloud.OverseerTaskQueue$QueueEvent@2e684ecf]]
[junit4] 2> 1221063 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-work/qn-0000000000] as running
[junit4] 2> 1221063 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 1221066 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Cleaning up work-queue. #Running tasks: 1 #Completed tasks: 0
[junit4] 2> 1221066 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor RunningTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 1221066 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor BlockedTasks: []
[junit4] 2> 1221066 DEBUG (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor CompletedTasks: []
[junit4] 2> 1221066 INFO (OverseerCollectionConfigSetProcessor-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor RunningZKTasks: [/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 1221066 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Runner processing /overseer/collection-queue-work/qn-0000000000
[junit4] 2> 1221066 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 1221066 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader$AliasesManager Checking ZK for most up to date Aliases /aliases.json
[junit4] 2> 1221067 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.c.ZkStateReader$AliasesManager Aliases: cmp=0, not overwriting ZK version.
[junit4] 2> 1221067 INFO (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1221067 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.a.c.OverseerCollectionMessageHandler creating collections conf node /collections/control_collection
[junit4] 2> 1221068 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.a.c.CreateCollectionCmd Check for collection zkNode: control_collection
[junit4] 2> 1221068 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.a.c.CreateCollectionCmd Collection zkNode exists
[junit4] 2> 1221069 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 1221070 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1221070 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 1221070 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/control_collection/state.json
[junit4] 2> 1221071 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 1221292 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"}
[junit4] 2> 1221293 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] 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:46023_ghq
[junit4] 2> 1221295 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1221295 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1221300 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 1221301 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[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> 1221301 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[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> 1221302 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1221302 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 1221402 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 0
[junit4] 2> 1221403 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 1222319 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222329 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1222330 WARN (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 1222333 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1222337 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222380 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222380 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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@33ba995e
[junit4] 2> 1222380 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1222383 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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=28, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=76.1591796875, floorSegmentMB=0.5390625, forceMergeDeletesPctAllowed=25.732323385097065, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5833639768254886, deletesPctAllowed=43.378009041863386
[junit4] 2> 1222415 WARN (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222476 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222476 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222477 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1222477 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1222479 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1222482 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222483 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1222483 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222483 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222483 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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 1701260859544698880
[junit4] 2> 1222485 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Waiting up to 60000ms for state org.apache.solr.cloud.ZkController$$Lambda$581/582423487@71b211a9
[junit4] 2> 1222485 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1222486 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222486 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222487 INFO (searcherExecutor-12157-thread-1-processing-n:127.0.0.1:46023_ghq x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:46023_ghq 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> 1222487 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222487 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222490 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Completed wait for org.apache.solr.cloud.ZkController$$Lambda$581/582423487@71b211a9
[junit4] 2> 1222490 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222492 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-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> 1222492 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222492 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222491 DEBUG (watches-12139-thread-1-processing-n:127.0.0.1:46023_ghq x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:46023_ghq 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> 1222492 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222493 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222495 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1222495 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222495 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46023/ghq/control_collection_shard1_replica_n1/
[junit4] 2> 1222497 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222498 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:46023/ghq/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1222498 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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/73597360926949380-core_node2-n_0000000000
[junit4] 2> 1222500 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 1222500 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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:46023/ghq/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1222500 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1222500 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"leader"} current state version: 0
[junit4] 2> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (watches-12139-thread-1-processing-n:127.0.0.1:46023_ghq x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:46023_ghq 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> 1222501 DEBUG (watches-12139-thread-2-processing-n:127.0.0.1:46023_ghq x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:46023_ghq 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> 1222601 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/control_collection/state.json version: 1
[junit4] 2> 1222602 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-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> 1222602 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-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> 1222602 INFO (zkCallback-12137-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> 1222602 DEBUG (zkCallback-12137-thread-1) [ ] o.a.s.c.c.ZkStateReader Updating data for [control_collection] from [1] to [2]
[junit4] 2> 1222602 DEBUG (zkCallback-12137-thread-1) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 1222602 DEBUG (watches-12139-thread-2) [ ] 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> "node_name":"127.0.0.1:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}
[junit4] 2> 1222602 DEBUG (watches-12139-thread-2) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [0] watched [0] lazy [2] total [2]
[junit4] 2> 1222603 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222603 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222604 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 1222604 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Add data for [control_collection] ver [2]
[junit4] 2> 1222604 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222604 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222605 DEBUG (qtp789582219-19405) [n:127.0.0.1:46023_ghq 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> 1222605 INFO (qtp789582219-19405) [n:127.0.0.1:46023_ghq ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={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} status=0 QTime=1310
[junit4] 2> 1222606 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/overseer/queue
[junit4] 2> 1222628 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.a.c.CreateCollectionCmd Finished create command on all shards for collection: control_collection
[junit4] 2> 1222628 INFO (zkCallback-12137-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> 1222628 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Completed task:[/overseer/collection-queue-work/qn-0000000000]
[junit4] 2> 1222628 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[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> 1222628 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[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> 1222628 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}
[junit4] 2> 1222628 DEBUG (OverseerStateUpdate-73597360926949380-127.0.0.1:46023_ghq-n_0000000000) [n:127.0.0.1:46023_ghq ] 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> "core":"control_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46023_ghq",
[junit4] 2> "base_url":"http://127.0.0.1:46023/ghq",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1222629 DEBUG (zkCallback-12137-thread-2) [ ] o.a.s.c.c.ZkStateReader clusterStateSet: legacy [0] interesting [1] watched [1] lazy [2] total [2]
[junit4] 2> 1222629 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:NodeDataChanged path:/overseer/collection-queue-work/qnr-0000000000
[junit4] 2> 1222629 DEBUG (TEST-ShardSplitTest.testSplitStaticIndexReplication-seed#[740D5BA9724EC413]-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> 1222629 DEBUG (OverseerThreadFactory-12145-thread-1-processing-n:127.0.0.1:46023_ghq) [n:127.0.0.1:46023_ghq ] o.a.s.c.OverseerTaskProcessor Marked task [/overseer/collection-queue-
[...truncated too long message...]
:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486668 DEBUG (closeThreadPool-13993-thread-2-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486669 INFO (closeThreadPool-14155-thread-9) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2a103db7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1486669 INFO (closeThreadPool-14155-thread-9) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1814a354{/ghq,null,STOPPED}
[junit4] 2> 1486669 INFO (closeThreadPool-14155-thread-9) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1486669 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1486770 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486871 DEBUG (TEST-ShardSplitTest.testSplitLocking-seed#[740D5BA9724EC413]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:Closed type:None path:null
[junit4] 2> 1486872 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> 18 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/collections/testSplitLocking/terms/shard1_1
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 6 /solr/collections/testSplitLocking/terms/shard1_0
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/collections/testSplitLocking/terms/shard1
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2> 5 /solr/collections/collection1/terms/shard1
[junit4] 2> 5 /solr/collections/collection1/terms/shard2
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 3 /solr/collections/testSplitLocking/collectionprops.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 90 /solr/collections/testSplitLocking/state.json
[junit4] 2> 62 /solr/clusterstate.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/73597376746684420-127.0.0.1:36527_ghq-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 81 /solr/overseer/queue
[junit4] 2> 29 /solr/live_nodes
[junit4] 2> 28 /solr/overseer/collection-queue-work
[junit4] 2> 13 /solr/collections/testSplitLocking/state.json
[junit4] 2> 13 /solr/collections
[junit4] 2> 4 /solr/collections/control_collection/state.json
[junit4] 2>
[junit4] 2> 1486875 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45185
[junit4] 2> 1486875 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45185
[junit4] 2> 1486875 INFO (TEST-ShardSplitTest.testSplitLocking-seed#[740D5BA9724EC413]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45185
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.cloud.api.collections.ShardSplitTest_740D5BA9724EC413-001
[junit4] 2> May 31, 2021 8:17:31 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {rnd_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), a_t=PostingsFormat(name=LuceneFixedGap), _root_=PostingsFormat(name=MockRandom), a_i=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), foo_s=PostingsFormat(name=LuceneFixedGap)}, docValues:{_version_=DocValuesFormat(name=Lucene80), a_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=714, maxMBSortInHeap=6.812614913095582, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=ar-OM, timezone=America/Santarem
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=254285288,total=512753664
[junit4] 2> NOTE: All tests run in this JVM: [TestMergePolicyConfig, TestDynamicFieldNamesIndexCorrectly, TestHttpShardHandlerFactory, ComputePlanActionTest, ForceLeaderWithTlogReplicasTest, TestCustomSort, TestUtils, TestHighFrequencyDictionaryFactory, TestJsonFacets, LegacyCloudClusterPropTest, TestRandomCollapseQParserPlugin, TestPartialUpdateDeduplication, AuditLoggerIntegrationTest, TestSolrConfigHandler, TestPerFieldSimilarityWithDefaultOverride, SubstringBytesRefFilterTest, HdfsChaosMonkeyNothingIsSafeTest, SolrXmlInZkTest, DistributedQueryComponentCustomSortTest, TestAuthorizationFramework, TestDeprecatedFilters, TestSizeLimitedDistributedMap, LeaderFailoverAfterPartitionTest, DistributedSuggestComponentTest, TestSolrCloudWithSecureImpersonation, TestCoreContainer, MetricsDisabledCloudTest, TestFieldTypeResource, SolrPluginUtilsTest, OpenExchangeRatesOrgProviderTest, SparseHLLTest, HdfsCloudIncrementalBackupTest, TestUtilizeNode, TestBinaryField, PrimitiveFieldTypeTest, DocValuesTest, TestFoldingMultitermQuery, TestDistribIDF, TestInPlaceUpdateWithRouteField, DirectoryFactoryTest, MultiSolrCloudTestCaseTest, TestGraphMLResponseWriter, ResponseLogComponentTest, SolrSlf4jReporterTest, TestRequestForwarding, DateFieldTest, ReturnFieldsTest, PeerSyncWithIndexFingerprintCachingTest, ZkCLITest, TestNumericTokenStream, TestRestoreCore, TestSnapshotCloudManager, TestAuthenticationFramework, CoreAdminHandlerTest, BackupRestoreApiErrorConditionsTest, DistributedFacetSimpleRefinementLongTailTest, SaslZkACLProviderTest, TestBinaryResponseWriter, CdcrOpsAndBoundariesTest, TestDeleteCollectionOnDownNodes, BasicDistributedZkTest, TestOrdValues, TestSolr4Spatial, TestEmbeddedSolrServerSchemaAPI, XsltUpdateRequestHandlerTest, MoreLikeThisComponentTest, AggValueSourceTest, TestRawResponseWriter, ClassificationUpdateProcessorIntegrationTest, TestSchemaVersionResource, StatsComponentTest, TestRestManager, TestCloudRecovery2, TestCollationField, DaemonStreamApiTest, BlockJoinFacetDistribTest, TestDFISimilarityFactory, TestSubQueryTransformerCrossCore, ReplaceNodeNoTargetTest, SimpleMLTQParserTest, TestManagedSchemaAPI, TestDelegationWithHadoopAuth, TestTlogReplayVsRecovery, TemplateUpdateProcessorTest, ParsingFieldUpdateProcessorsTest, HdfsRecoveryZkTest, DistributedFacetPivotSmallAdvancedTest, DistanceUnitsTest, TestQueryingOnDownCollection, TestCloudJSONFacetSKG, TestStandardQParsers, TestDocTermOrdsUninvertLimit, TestDynamicLoading, TestTextField, MetricsHistoryHandlerTest, DocumentBuilderTest, TestMaxScoreQueryParser, TestDistributedGrouping, TestScoreJoinQPScore, TestFieldCacheSortRandom, NoCacheHeaderTest, TestMacroExpander, TestSolrXml, LoggingHandlerTest, TestConfigOverlay, TestRecovery, TokenizerChainTest, DeleteLastCustomShardedReplicaTest, TestGroupingSearch, ShardSplitTest]
[junit4] Completed [507/939 (1!)] on J3 in 267.90s, 11 tests, 1 error, 3 skipped <<< FAILURES!
[...truncated 54029 lines...]
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 295 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/295/
3 tests failed.
FAILED: org.apache.solr.cloud.LeaderTragicEventTest.testLeaderFailsOver
Error Message:
Error from server at https://127.0.0.1:40127/solr: Underlying core creation failed while creating collection: testLeaderFailsOver
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40127/solr: Underlying core creation failed while creating collection: testLeaderFailsOver
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.cloud.LeaderTragicEventTest.testLeaderFailsOver(LeaderTragicEventTest.java:80)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
action did not execute
Stack Trace:
java.lang.AssertionError: action did not execute
at __randomizedtesting.SeedInfo.seed([A4B6924471DBAD19:C94A36B9CB93521E]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:285)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.metrics.reporters.SolrJmxReporterTest.testClosedCore
Error Message:
Captured an uncaught exception in thread: Thread[id=36497, name=Thread-5054, state=RUNNABLE, group=TGRP-SolrJmxReporterTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=36497, name=Thread-5054, state=RUNNABLE, group=TGRP-SolrJmxReporterTest]
at __randomizedtesting.SeedInfo.seed([A4B6924471DBAD19:D3CB44645142CFE9]:0)
Caused by: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([A4B6924471DBAD19]:0)
at org.junit.Assert.fail(Assert.java:87)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.junit.Assert.assertNotNull(Assert.java:713)
at org.junit.Assert.assertNotNull(Assert.java:723)
at org.apache.solr.metrics.reporters.SolrJmxReporterTest$1.run(SolrJmxReporterTest.java:213)
Build Log:
[...truncated 13600 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 147201 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-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-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 147201 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 147202 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A4B6924471DBAD19-001/data-dir-15-001
[junit4] 2> 147202 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=36 numCloses=36
[junit4] 2> 147202 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 147204 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 147204 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 147205 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A4B6924471DBAD19-001/tempDir-001
[junit4] 2> 147205 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 147205 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 147206 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 147206 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 147306 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 37425
[junit4] 2> 147306 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:37425
[junit4] 2> 147306 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37425
[junit4] 2> 147306 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37425
[junit4] 2> 147307 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147310 INFO (zkConnectionManagerCallback-810-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 147310 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147312 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147317 INFO (zkConnectionManagerCallback-812-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 147317 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147318 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147319 INFO (zkConnectionManagerCallback-814-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 147319 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147423 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 147423 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 147423 INFO (jetty-launcher-815-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_281-b09
[junit4] 2> 147424 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 147424 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 147424 INFO (jetty-launcher-815-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_281-b09
[junit4] 2> 147436 INFO (jetty-launcher-815-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 147436 INFO (jetty-launcher-815-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 147436 INFO (jetty-launcher-815-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 147437 INFO (jetty-launcher-815-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d070e02{/solr,null,AVAILABLE}
[junit4] 2> 147438 INFO (jetty-launcher-815-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 147438 INFO (jetty-launcher-815-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 147438 INFO (jetty-launcher-815-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 147440 INFO (jetty-launcher-815-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3888dd00{/solr,null,AVAILABLE}
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@32008b16{SSL, (ssl, http/1.1)}{127.0.0.1:39363}
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.e.j.s.Server Started @147479ms
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:37425/solr, hostPort=39363}
[junit4] 2> 147441 ERROR (jetty-launcher-815-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 147441 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-06-01T08:38:26.863Z
[junit4] 2> 147443 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@24362f8c{SSL, (ssl, http/1.1)}{127.0.0.1:42331}
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.e.j.s.Server Started @147483ms
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:37425/solr, hostPort=42331}
[junit4] 2> 147445 INFO (zkConnectionManagerCallback-817-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147445 ERROR (jetty-launcher-815-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 147445 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-06-01T08:38:26.867Z
[junit4] 2> 147446 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 147446 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 147448 INFO (zkConnectionManagerCallback-819-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 147448 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 147449 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 147451 WARN (jetty-launcher-815-thread-1-SendThread(127.0.0.1:37425)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1057da67f7a0003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1057da67f7a0003, 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 0x1057da67f7a0003, 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> 148095 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 148096 WARN (jetty-launcher-815-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> 148097 WARN (jetty-launcher-815-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@77d2c9aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148097 WARN (jetty-launcher-815-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@77d2c9aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148099 WARN (jetty-launcher-815-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> 148100 WARN (jetty-launcher-815-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5f1c7e69[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148100 WARN (jetty-launcher-815-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5f1c7e69[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148102 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37425/solr
[junit4] 2> 148102 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148128 INFO (zkConnectionManagerCallback-833-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 148129 INFO (jetty-launcher-815-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148250 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148276 INFO (zkConnectionManagerCallback-835-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 148276 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148276 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 148280 WARN (jetty-launcher-815-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> 148317 WARN (jetty-launcher-815-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4398b154[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148317 WARN (jetty-launcher-815-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4398b154[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148340 WARN (jetty-launcher-815-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> 148348 WARN (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 148361 WARN (jetty-launcher-815-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@561f3aaa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148361 WARN (jetty-launcher-815-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@561f3aaa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 148362 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37425/solr
[junit4] 2> 148366 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42331_solr
[junit4] 2> 148366 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer Overseer (id=73603122978226182-127.0.0.1:42331_solr-n_0000000000) starting
[junit4] 2> 148392 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148405 INFO (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42331_solr
[junit4] 2> 148427 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42331_solr
[junit4] 2> 148428 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 148429 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 148429 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 148430 WARN (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_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> 148437 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 148437 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 148438 INFO (zkConnectionManagerCallback-845-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 148438 INFO (jetty-launcher-815-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148446 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 148457 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:42331_solr]
[junit4] 2> 148484 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 148512 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 148513 DEBUG (OverseerAutoScalingTriggerThread-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 148520 DEBUG (ScheduledTrigger-848-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 148521 WARN (jetty-launcher-815-thread-1-SendThread(127.0.0.1:37425)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1057da67f7a0007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1057da67f7a0007, 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 0x1057da67f7a0007, 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> 148529 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42331.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 148557 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42331.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 148557 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42331.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 148558 INFO (jetty-launcher-815-thread-2) [n:127.0.0.1:42331_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A4B6924471DBAD19-001/tempDir-001/node1
[junit4] 2> 148656 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148668 INFO (zkConnectionManagerCallback-857-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 148668 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148671 WARN (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 148673 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 148675 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39363_solr as DOWN
[junit4] 2> 148684 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 148684 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39363_solr
[junit4] 2> 148685 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 148685 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:39363_solr
[junit4] 2> 148685 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 148686 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 148687 WARN (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_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> 148692 INFO (zkCallback-856-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 148700 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 148727 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39363.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 148758 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39363.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 148758 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39363.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 148776 INFO (jetty-launcher-815-thread-1) [n:127.0.0.1:39363_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A4B6924471DBAD19-001/tempDir-001/node2
[junit4] 2> 148872 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 148878 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 148880 INFO (zkConnectionManagerCallback-870-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 148880 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 148894 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 148895 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37425/solr ready
[junit4] 2> 148955 INFO (OverseerThreadFactory-850-thread-1-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 148962 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":".system",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":1,
[junit4] 2> "createNodeSet":"127.0.0.1:42331_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 148962 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 148962 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 149066 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 149066 INFO (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 149067 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 149067 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_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":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr"}}}
[junit4] 2> 149168 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 149286 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 149286 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 149292 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 149292 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 149293 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 149293 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/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":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 149301 WARN (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig You should not use LATEST as luceneMatchVersion property: if you use this setting, and then Solr upgrades to a newer release of Lucene, sizable changes may happen. If precise back compatibility is important then you should instead explicitly specify an actual Lucene version.
[junit4] 2> 149301 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 149302 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 149304 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 149304 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 149306 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 149308 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 149308 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
[junit4] 2> 149309 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42331.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e1cdbb3
[junit4] 2> 149309 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A4B6924471DBAD19-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_A4B6924471DBAD19-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 149378 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 149378 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 149379 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 149379 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 149392 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 149392 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 149394 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 149396 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 149397 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 149397 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 149398 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1701353051987640320
[junit4] 2> 149405 INFO (searcherExecutor-872-thread-1-processing-n:127.0.0.1:42331_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 149420 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 149420 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 149424 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 149424 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 149424 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42331/solr/.system_shard1_replica_n1/
[junit4] 2> 149425 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 149425 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:42331/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 149425 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/73603122978226182-core_node2-n_0000000000
[junit4] 2> 149427 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42331/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 149428 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"leader"} current state version: 0
[junit4] 2> 149521 DEBUG (ScheduledTrigger-848-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 149529 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 149530 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 149533 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 149555 INFO (qtp785270012-1280) [n:127.0.0.1:42331_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=270
[junit4] 2> 149560 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 149560 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 149560 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 149560 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/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":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:42331_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42331/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 149560 INFO (qtp785270012-1273) [n:127.0.0.1:42331_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> 149565 INFO (zkCallback-834-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 149661 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 149661 INFO (zkCallback-834-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 149661 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 149663 INFO (qtp785270012-1273) [n:127.0.0.1:42331_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:42331_solr&wt=javabin&version=2} status=0 QTime=730
[junit4] 2> 149667 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 149674 INFO (zkCallback-834-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 149693 INFO (OverseerCollectionConfigSetProcessor-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_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> 149708 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 149709 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 149711 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 149713 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 149714 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 149721 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 149722 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 149724 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 149725 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 149726 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 149727 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 149728 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 149730 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 149731 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 149732 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 149733 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 149735 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 149736 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 149737 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 149740 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 149742 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 149748 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 149749 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 149750 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 149752 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 149753 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 149757 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 149759 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 149761 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 149764 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 149765 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 149767 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 149768 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 149769 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 149770 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 149772 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 149773 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 149774 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 149776 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 149777 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 149778 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 149780 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 149781 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 149782 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 149783 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 149784 INFO (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 149788 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":"1",
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "createNodeSet":"127.0.0.1:39363_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 149788 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 149889 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 149990 WARN (OverseerThreadFactory-850-thread-2-processing-n:127.0.0.1:42331_solr) [n:127.0.0.1:42331_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 149994 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 149994 INFO (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 149996 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 149996 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"}}}
[junit4] 2> 149996 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 149996 INFO (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 149998 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"}}}
[junit4] 2> 149998 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"}}}
[junit4] 2> 149999 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 149999 INFO (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 150000 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"}}}
[junit4] 2> 150000 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"}}}
[junit4] 2> 150100 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 150247 INFO (qtp955834470-1279) [n:127.0.0.1:39363_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n4&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 150258 INFO (qtp955834470-1284) [n:127.0.0.1:39363_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 150262 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 150262 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 150262 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 150262 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr"}}}}}
[junit4] 2> 150273 DEBUG (OverseerStateUpdate-73603122978226182-127.0.0.1:42331_solr-n_0000000000) [n:127.0.0.1:42331_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:39363_solr",
[junit4] 2> "base_url":"https://127.0.0.1:39363/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[...truncated too long message...]
lrJmxReporterTest-xhbwqipvn, domain = solr.core.collection1, service url = null, agent id = localhost_1622538644098] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@58a58869
[junit4] 2> 2088115 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@111e4836
[junit4] 2> 2088115 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@111e4836
[junit4] 2> 2088115 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> giu 01, 2021 11:10:47 AM com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> AVVERTENZA: Uncaught exception in thread: Thread[Thread-5054,5,TGRP-SolrJmxReporterTest]
[junit4] 2> java.lang.AssertionError
[junit4] 2> at __randomizedtesting.SeedInfo.seed([A4B6924471DBAD19]:0)
[junit4] 2> at org.junit.Assert.fail(Assert.java:87)
[junit4] 2> at org.junit.Assert.assertTrue(Assert.java:42)
[junit4] 2> at org.junit.Assert.assertNotNull(Assert.java:713)
[junit4] 2> at org.junit.Assert.assertNotNull(Assert.java:723)
[junit4] 2> at org.apache.solr.metrics.reporters.SolrJmxReporterTest$1.run(SolrJmxReporterTest.java:213)
[junit4] 2>
[junit4] 2> 2090119 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=null
[junit4] 2> 2090119 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@111e4836
[junit4] 2> 2090119 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2090119 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=41446197
[junit4] 2> 2090119 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2090119 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2a3363db: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@e4cebc2
[junit4] 2> 2090125 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2090125 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4647ca7c: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@f31119a
[junit4] 2> 2090128 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2090128 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e79270b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@11750701
[junit4] 2> 2090130 INFO (TEST-SolrJmxReporterTest.testClosedCore-seed#[A4B6924471DBAD19]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testClosedCore
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrJmxReporterTest -Dtests.method=testClosedCore -Dtests.seed=A4B6924471DBAD19 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=it -Dtests.timezone=Africa/Harare -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 3.21s J1 | SolrJmxReporterTest.testClosedCore <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=36497, name=Thread-5054, state=RUNNABLE, group=TGRP-SolrJmxReporterTest]
[junit4] > at __randomizedtesting.SeedInfo.seed([A4B6924471DBAD19:D3CB44645142CFE9]:0)
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([A4B6924471DBAD19]:0)
[junit4] > at org.apache.solr.metrics.reporters.SolrJmxReporterTest$1.run(SolrJmxReporterTest.java:213)
[junit4] 2> 2090131 INFO (SUITE-SolrJmxReporterTest-seed#[A4B6924471DBAD19]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=1219, maxMBSortInHeap=6.758850279323291, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=it, timezone=Africa/Harare
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=191512080,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterFSTTest, JvmMetricsTest, BitVectorTest, TestFaceting, HdfsNNFailoverTest, TestFieldCacheReopen, RestoreTriggerStateTest, TestQueryUtils, CloneFieldUpdateProcessorFactoryTest, TestSimNodeAddedTrigger, TestSimpleQParserPlugin, FileBasedSpellCheckerTest, TestSchemaResource, TriggerCooldownIntegrationTest, TestCrossCoreJoin, CdcrRequestHandlerTest, TestLocalFSCloudBackupRestore, AutoscalingHistoryHandlerTest, TestUninvertingReader, TestSegmentSorting, TestReversedWildcardFilterFactory, TestSystemIdResolver, TestConfigSetProperties, ZkStateReaderTest, TestExactStatsCache, SolrIndexSearcherTest, TestLegacyFieldReuse, BasicAuthIntegrationTest, ShardRoutingTest, TestNumericRangeQuery32, SpatialHeatmapFacetsTest, PackageManagerCLITest, TestSimClusterStateProvider, TestFieldResource, NotRequiredUniqueKeyTest, MaxScoreCollectorTest, TestDynamicURP, TestHalfAndHalfDocValues, BlockJoinFacetSimpleTest, TestJoin, MoreLikeThisHandlerTest, SearchRateTriggerIntegrationTest, TestSolrCoreProperties, DataDrivenBlockJoinTest, TestBlockCollapse, TestUseDocValuesAsStored2, ZkFailoverTest, SolrCoreTest, CreateRoutedAliasTest, TestSimExecutePlanAction, TestHdfsBackupRestoreCore, TestNestedDocsSort, TestReRankQParserPlugin, TestIBSimilarityFactory, TestExceedMaxTermLength, DebugComponentTest, TestDistributedStatsComponentCardinality, TestSchemalessBufferedUpdates, TestStressInPlaceUpdates, TestReload, TestCopyFieldCollectionResource, TestSQLHandlerNonCloud, ClusterStateTest, TestBlobHandler, TestUnifiedSolrHighlighterWithoutStoredId, V2CollectionBackupsAPIMappingTest, TestAnalyzeInfixSuggestions, CollectionsAPISolrJTest, CSVRequestHandlerTest, TestMissingGroups, JWTAuthPluginTest, TestCollectionAPIs, ConcurrentDeleteAndCreateCollectionTest, TestPullReplicaErrorHandling, TestStressIncrementalBackup, DistributedFacetPivotWhiteBoxTest, TestComplexPhraseQParserPlugin, BadCopyFieldTest, TestLRUCache, TestXmlQParser, ReplicationFactorTest, RulesTest, SuggestComponentTest, TestRTimerTree, TestManagedResource, SolrIndexConfigTest, TestJsonRequest, TestPKIAuthenticationPlugin, TestTrackingShardHandlerFactory, SpellPossibilityIteratorTest, RankQParserPluginTest, TestUnInvertedFieldException, TestHashPartitioner, VersionInfoTest, IndexSizeEstimatorTest, HLLUtilTest, TestDocBasedVersionConstraints, MBeansHandlerTest, UnloadDistributedZkTest, ShardsWhitelistTest, TestCustomDocTransformer, TestInPlaceUpdatesRequiredField, TestDistributedTracing, AssignBackwardCompatibilityTest, HighlighterTest, QueryElevationComponentTest, TestFilteredDocIdSet, TestCloudDeleteByQuery, ReindexCollectionTest, DistributedIntervalFacetingTest, DOMUtilTest, TestShardHandlerFactory, DirectSolrConnectionTest, TestSimTriggerIntegration, ProtectedTermFilterFactoryTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CloudExitableDirectoryReaderTest, CollectionPropsTest, DeleteStatusTest, DistribDocExpirationUpdateProcessorTest, HttpPartitionWithTlogReplicasTest, LeaderFailoverAfterPartitionTest, LegacyCloudClusterPropTest, MetricsHistoryWithAuthIntegrationTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, OverseerCollectionConfigSetProcessorTest, OverseerSolrResponseTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, RecoveryAfterSoftCommitTest, SystemCollectionCompatTest, TestCloudPivotFacet, TestClusterProperties, TestConfigSetsAPI, TestCryptoKeys, TestDownShardTolerantSearch, TestLockTree, TestMiniSolrCloudClusterSSL, TestSolrCloudWithDelegationTokens, TestTolerantUpdateProcessorCloud, TestWithCollection, PurgeGraphTest, SimpleCollectionCreateDeleteTest, TestCollectionAPI, ExecutePlanActionTest, IndexSizeTriggerTest, ConfigureRecoveryStrategyTest, CoreSorterTest, DirectoryFactoriesTest, ExitableDirectoryReaderTest, HdfsDirectoryFactoryTest, RAMDirectoryFactoryTest, TestBadConfig, TestInitParams, TestJmxIntegration, TestLazyCores, TestSolrIndexConfig, BackupIdTest, HdfsBackupRepositoryIntegrationTest, FieldAnalysisRequestHandlerTest, RequestLoggingTest, TestReplicationHandlerDiskOverFlow, TestSolrConfigHandlerCloud, DistributedQueryComponentOptimizationTest, DistributedSpellCheckComponentTest, TaggerTest, XmlInterpolationTest, TestPostingsSolrHighlighter, SolrCoreMetricManagerTest, SolrJmxReporterTest]
[junit4] Completed [732/939 (3!)] on J1 in 5.63s, 4 tests, 1 error <<< FAILURES!
[...truncated 53278 lines...]