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 2019/12/25 03:31:53 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 3960 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/3960/
1 tests failed.
FAILED: org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests
Error Message:
Alias should not exist
Stack Trace:
java.lang.AssertionError: Alias should not exist
at __randomizedtesting.SeedInfo.seed([6BCA0FF14F222948:8F8E3346E98A6797]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest.testAsyncRequests(CollectionsAPIAsyncDistributedZkTest.java:194)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 12936 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest
[junit4] 2> 159419 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6BCA0FF14F222948]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 159436 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6BCA0FF14F222948]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/data-dir-4-001
[junit4] 2> 159436 WARN (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6BCA0FF14F222948]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=30 numCloses=30
[junit4] 2> 159436 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6BCA0FF14F222948]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 159437 INFO (SUITE-CollectionsAPIAsyncDistributedZkTest-seed#[6BCA0FF14F222948]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 159534 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAsyncIdBackCompat
[junit4] 2> 159534 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-001
[junit4] 2> 159535 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 159535 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 159536 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 159643 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer start zk server on port:45725
[junit4] 2> 159643 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45725
[junit4] 2> 159643 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45725
[junit4] 2> 159643 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45725
[junit4] 2> 159672 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 159746 INFO (zkConnectionManagerCallback-277-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 159746 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 159827 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 159900 INFO (zkConnectionManagerCallback-279-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 159900 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 159997 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160067 INFO (zkConnectionManagerCallback-281-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 160070 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160293 WARN (jetty-launcher-282-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 160293 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 160293 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 160293 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 160359 WARN (jetty-launcher-282-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 160360 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 160360 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 160360 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 160417 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 160417 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 160417 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 160433 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15d02654{/solr,null,AVAILABLE}
[junit4] 2> 160434 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 160434 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 160434 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 160434 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5a784bdc{/solr,null,AVAILABLE}
[junit4] 2> 160454 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@15773969{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:৩৫৯২৮}
[junit4] 2> 160454 INFO (jetty-launcher-282-thread-2) [ ] o.e.j.s.Server Started @১৬০৫০২ms
[junit4] 2> 160454 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35928}
[junit4] 2> 160455 ERROR (jetty-launcher-282-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 160455 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 160455 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 160455 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 160455 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 160455 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-25T01:13:38.112223Z
[junit4] 2> 160506 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@48b36f1b{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:৩৯৯২৬}
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.e.j.s.Server Started @১৬০৫৫৬ms
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39926}
[junit4] 2> 160508 ERROR (jetty-launcher-282-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 160508 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-25T01:13:38.165939Z
[junit4] 2> 160526 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 160554 INFO (zkConnectionManagerCallback-284-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 160555 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160572 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 160656 INFO (zkConnectionManagerCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 160672 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 160726 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 161464 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 161568 WARN (jetty-launcher-282-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5473422e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161568 WARN (jetty-launcher-282-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5473422e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161569 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 161618 WARN (jetty-launcher-282-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@489a5b7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161618 WARN (jetty-launcher-282-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@489a5b7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161619 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45725/solr
[junit4] 2> 161620 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 161634 WARN (jetty-launcher-282-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2c34c7a1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161634 WARN (jetty-launcher-282-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2c34c7a1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161670 INFO (zkConnectionManagerCallback-295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 161706 INFO (jetty-launcher-282-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 161729 WARN (jetty-launcher-282-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@76454a91[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161729 WARN (jetty-launcher-282-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@76454a91[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 161730 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45725/solr
[junit4] 2> 161759 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 161796 INFO (zkConnectionManagerCallback-300-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 161796 INFO (jetty-launcher-282-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 161892 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 161962 INFO (zkConnectionManagerCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 161962 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 162093 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 162146 INFO (zkConnectionManagerCallback-304-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 162146 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 164157 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39926_solr
[junit4] 2> 164191 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.c.Overseer Overseer (id=75961823786237959-127.0.0.1:39926_solr-n_0000000000) starting
[junit4] 2> 164265 INFO (OverseerStateUpdate-75961823786237959-127.0.0.1:39926_solr-n_0000000000) [n:127.0.0.1:39926_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:39926_solr
[junit4] 2> 164301 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39926_solr
[junit4] 2> 164318 WARN (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_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> 164338 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 164449 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 164502 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35928_solr as DOWN
[junit4] 2> 164504 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 164504 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35928_solr
[junit4] 2> 164575 WARN (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_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> 164603 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 164647 INFO (zkCallback-303-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 164647 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 164795 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 165215 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35928.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 165254 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39926.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 165387 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39926.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 165387 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39926.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 165413 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35928.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 165432 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35928.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 165449 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 165451 INFO (jetty-launcher-282-thread-2) [n:127.0.0.1:35928_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-001/node2/.
[junit4] 2> 165460 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 165461 INFO (jetty-launcher-282-thread-1) [n:127.0.0.1:39926_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-001/node1/.
[junit4] 2> 166014 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 166034 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 166070 INFO (zkConnectionManagerCallback-317-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 166071 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 166105 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 166125 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45725/solr ready
[junit4] 2> 166505 INFO (qtp122260409-826) [n:127.0.0.1:39926_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=testAsyncIdBackCompat&collection.configName=conf1&name=testAsyncIdBackCompat&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 166507 INFO (qtp122260409-826) [n:127.0.0.1:39926_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=testAsyncIdBackCompat&collection.configName=conf1&name=testAsyncIdBackCompat&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 166508 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAsyncIdBackCompat
[junit4] 2> 166687 INFO (jetty-closer-318-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2079951977
[junit4] 2> 166687 INFO (jetty-closer-318-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39926_solr
[junit4] 2> 166688 INFO (jetty-closer-318-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1755951466
[junit4] 2> 166688 INFO (jetty-closer-318-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:35928_solr
[junit4] 2> 166690 INFO (jetty-closer-318-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 166690 INFO (jetty-closer-318-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:39926_solr as DOWN
[junit4] 2> 166692 INFO (jetty-closer-318-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 166692 INFO (jetty-closer-318-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:35928_solr as DOWN
[junit4] 2> 166742 INFO (jetty-closer-318-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 166876 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 166877 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4870f706: rootName = solr_39926, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@511fa148
[junit4] 2> 166896 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 166897 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f7868a: rootName = solr_35928, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@68debb97
[junit4] 2> 166973 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 166973 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e6b3654: rootName = solr_39926, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10f8722
[junit4] 2> 166988 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 166988 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@47142276: rootName = solr_35928, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10f8722
[junit4] 2> 167008 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 167008 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@68681400: rootName = solr_35928, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@a04f013
[junit4] 2> 167008 INFO (jetty-closer-318-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 167023 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 167023 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5cb07b8f: rootName = solr_39926, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@a04f013
[junit4] 2> 167024 INFO (jetty-closer-318-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 167092 INFO (closeThreadPool-326-thread-2) [ ] o.a.s.c.Overseer Overseer (id=75961823786237959-127.0.0.1:39926_solr-n_0000000000) closing
[junit4] 2> 167092 INFO (OverseerStateUpdate-75961823786237959-127.0.0.1:39926_solr-n_0000000000) [n:127.0.0.1:39926_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39926_solr
[junit4] 2> 167108 INFO (OverseerAutoScalingTriggerThread-75961823786237959-127.0.0.1:39926_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 167218 INFO (closeThreadPool-326-thread-1) [ ] o.a.s.c.Overseer Overseer (id=75961823786237959-127.0.0.1:39926_solr-n_0000000000) closing
[junit4] 2> 167257 INFO (jetty-closer-318-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@15773969{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:০}
[junit4] 2> 167257 INFO (jetty-closer-318-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@15d02654{/solr,null,UNAVAILABLE}
[junit4] 2> 167257 INFO (jetty-closer-318-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 167470 INFO (jetty-closer-318-thread-1) [ ] o.a.s.c.Overseer Overseer (id=75961823786237959-127.0.0.1:39926_solr-n_0000000000) closing
[junit4] 2> 167491 INFO (jetty-closer-318-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@48b36f1b{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:০}
[junit4] 2> 167491 INFO (jetty-closer-318-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5a784bdc{/solr,null,UNAVAILABLE}
[junit4] 2> 167491 INFO (jetty-closer-318-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 167492 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 167786 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 167879 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45725
[junit4] 2> 167879 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45725
[junit4] 2> 167879 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdBackCompat-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45725
[junit4] 2> 167951 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSolrJAPICalls
[junit4] 2> 167952 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-002
[junit4] 2> 167952 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 167998 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 167999 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 168096 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer start zk server on port:45281
[junit4] 2> 168096 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45281
[junit4] 2> 168096 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45281
[junit4] 2> 168096 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45281
[junit4] 2> 168186 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 168275 INFO (zkConnectionManagerCallback-328-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 168275 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 168390 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 168441 INFO (zkConnectionManagerCallback-330-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 168441 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 168511 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 168554 INFO (zkConnectionManagerCallback-332-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 168568 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 168763 WARN (jetty-launcher-333-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 168776 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 168776 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 168776 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 168797 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 168797 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 168797 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 168798 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2b53795e{/solr,null,AVAILABLE}
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52a21d72{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:৩৪১৮৭}
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.e.j.s.Server Started @১৬৮৮৬২ms
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34187}
[junit4] 2> 168814 ERROR (jetty-launcher-333-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 168814 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-25T01:13:46.471696Z
[junit4] 2> 168829 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 168832 WARN (jetty-launcher-333-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 168832 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 168832 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 168832 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 168915 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 168915 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 168915 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 168927 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@893d918{/solr,null,AVAILABLE}
[junit4] 2> 168928 INFO (zkConnectionManagerCallback-335-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 168935 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@13a9504b{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:৪৩৫৫৮}
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.e.j.s.Server Started @১৬৯০০০ms
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43558}
[junit4] 2> 168952 ERROR (jetty-launcher-333-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 168952 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-25T01:13:46.609782Z
[junit4] 2> 168964 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 168972 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 169101 INFO (zkConnectionManagerCallback-337-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 169101 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 169305 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 169727 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 169908 WARN (jetty-launcher-333-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7ce584c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 169908 WARN (jetty-launcher-333-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7ce584c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 170001 WARN (jetty-launcher-333-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6fc0af04[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 170001 WARN (jetty-launcher-333-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6fc0af04[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 170002 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45281/solr
[junit4] 2> 170037 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 170086 INFO (zkConnectionManagerCallback-345-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 170090 INFO (jetty-launcher-333-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 170289 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 170419 INFO (zkConnectionManagerCallback-347-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 170419 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 171032 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 171052 WARN (jetty-launcher-333-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1da7ab61[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 171052 WARN (jetty-launcher-333-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1da7ab61[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 171089 WARN (jetty-launcher-333-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a22d2f7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 171089 WARN (jetty-launcher-333-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a22d2f7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 171090 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45281/solr
[junit4] 2> 171091 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 171126 INFO (zkConnectionManagerCallback-355-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 171126 INFO (jetty-launcher-333-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 171323 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 171543 INFO (zkConnectionManagerCallback-357-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 171544 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 171735 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43558_solr
[junit4] 2> 171753 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.c.Overseer Overseer (id=75961824337788936-127.0.0.1:43558_solr-n_0000000000) starting
[junit4] 2> 171806 INFO (OverseerStateUpdate-75961824337788936-127.0.0.1:43558_solr-n_0000000000) [n:127.0.0.1:43558_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43558_solr
[junit4] 2> 171807 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43558_solr
[junit4] 2> 171828 INFO (OverseerStateUpdate-75961824337788936-127.0.0.1:43558_solr-n_0000000000) [n:127.0.0.1:43558_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 171844 WARN (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_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> 171863 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 171881 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34187_solr as DOWN
[junit4] 2> 171900 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 171900 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34187_solr
[junit4] 2> 171936 WARN (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_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> 171996 INFO (zkCallback-356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 172025 INFO (zkCallback-346-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 172119 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 172191 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 172586 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34187.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 172678 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43558.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 172751 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34187.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 172752 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34187.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 172753 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 172785 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43558.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 172785 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43558.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 172823 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 172825 INFO (jetty-launcher-333-thread-2) [n:127.0.0.1:43558_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-002/node2/.
[junit4] 2> 172825 INFO (jetty-launcher-333-thread-1) [n:127.0.0.1:34187_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-002/node1/.
[junit4] 2> 173497 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 173566 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 173603 INFO (zkConnectionManagerCallback-368-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 173603 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 173623 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 173624 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testSolrJAPICalls-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45281/solr ready
[junit4] 2> 173951 INFO (qtp1548992394-947) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=91fd0099-8200-46ea-acdc-499d05a372d0&collection.configName=conf1&name=testasynccollectioncreation&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 174025 INFO (qtp1548992394-947) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=91fd0099-8200-46ea-acdc-499d05a372d0&collection.configName=conf1&name=testasynccollectioncreation&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=106
[junit4] 2> 174044 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 174062 INFO (OverseerThreadFactory-177-thread-1-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testasynccollectioncreation
[junit4] 2> 174063 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=19
[junit4] 2> 174720 INFO (OverseerStateUpdate-75961824337788936-127.0.0.1:43558_solr-n_0000000000) [n:127.0.0.1:43558_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testasynccollectioncreation",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testasynccollectioncreation_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34187/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 175071 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 175143 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=72
[junit4] 2> 175204 INFO (qtp492366482-936) [n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=testasynccollectioncreation&version=2&replicaType=NRT&async=91fd0099-8200-46ea-acdc-499d05a372d059573833230129583&coreNodeName=core_node2&name=testasynccollectioncreation_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin} status=0 QTime=20
[junit4] 2> 175212 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&collection=testasynccollectioncreation&version=2&replicaType=NRT&async=91fd0099-8200-46ea-acdc-499d05a372d059573833230129583&coreNodeName=core_node2&name=testasynccollectioncreation_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 175665 INFO (qtp492366482-934) [n:127.0.0.1:34187_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583
[junit4] 2> 175665 INFO (qtp492366482-934) [n:127.0.0.1:34187_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=91fd0099-8200-46ea-acdc-499d05a372d059573833230129583&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 176153 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 176225 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=71
[junit4] 2> 176684 INFO (qtp492366482-937) [n:127.0.0.1:34187_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583
[junit4] 2> 176684 INFO (qtp492366482-937) [n:127.0.0.1:34187_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=91fd0099-8200-46ea-acdc-499d05a372d059573833230129583&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 176772 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 176884 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.s.IndexSchema [testasynccollectioncreation_shard1_replica_n1] Schema name=minimal
[junit4] 2> 176899 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 176899 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testasynccollectioncreation_shard1_replica_n1' using configuration from collection testasynccollectioncreation, trusted=true
[junit4] 2> 176900 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34187.solr.core.testasynccollectioncreation.shard1.replica_n1' (registry 'solr.core.testasynccollectioncreation.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 176900 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.SolrCore [[testasynccollectioncreation_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-002/node1/testasynccollectioncreation_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001/tempDir-002/node1/./testasynccollectioncreation_shard1_replica_n1/data/]
[junit4] 2> 177228 INFO (qtp1548992394-944) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 177229 INFO (qtp1548992394-944) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 177385 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 177385 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 177528 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 177529 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 177569 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18fac7[testasynccollectioncreation_shard1_replica_n1] main]
[junit4] 2> 177570 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 177571 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 177621 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 177621 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1653852272358064128
[junit4] 2> 177708 INFO (qtp492366482-936) [n:127.0.0.1:34187_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583
[junit4] 2> 177708 INFO (qtp492366482-936) [n:127.0.0.1:34187_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=91fd0099-8200-46ea-acdc-499d05a372d059573833230129583&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 177714 INFO (searcherExecutor-186-thread-1-processing-x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 r:core_node2 CREATE n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.SolrCore [testasynccollectioncreation_shard1_replica_n1] Registered new searcher Searcher@18fac7[testasynccollectioncreation_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 177836 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testasynccollectioncreation/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 177837 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testasynccollectioncreation/leaders/shard1
[junit4] 2> 177854 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 177854 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 177854 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34187/solr/testasynccollectioncreation_shard1_replica_n1/
[junit4] 2> 177855 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 177855 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34187/solr/testasynccollectioncreation_shard1_replica_n1/ has no replicas
[junit4] 2> 177855 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testasynccollectioncreation/leaders/shard1/leader after winning as /collections/testasynccollectioncreation/leader_elect/shard1/election/75961824337788934-core_node2-n_0000000000
[junit4] 2> 177875 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34187/solr/testasynccollectioncreation_shard1_replica_n1/ shard1
[junit4] 2> 177890 INFO (zkCallback-346-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 177926 INFO (parallelCoreAdminExecutor-179-thread-1-processing-n:127.0.0.1:34187_solr x:testasynccollectioncreation_shard1_replica_n1 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583 CREATE) [n:127.0.0.1:34187_solr c:testasynccollectioncreation s:shard1 r:core_node2 x:testasynccollectioncreation_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 178007 INFO (zkCallback-346-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 178040 INFO (zkCallback-346-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 178040 INFO (zkCallback-346-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 178060 INFO (zkCallback-346-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 178263 INFO (qtp1548992394-947) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 178276 INFO (qtp1548992394-947) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 178717 INFO (qtp492366482-938) [n:127.0.0.1:34187_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 91fd0099-8200-46ea-acdc-499d05a372d059573833230129583
[junit4] 2> 178717 INFO (qtp492366482-938) [n:127.0.0.1:34187_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=91fd0099-8200-46ea-acdc-499d05a372d059573833230129583&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 178769 INFO (OverseerThreadFactory-177-thread-1-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_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> 179290 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 179299 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 179325 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 179334 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=91fd0099-8200-46ea-acdc-499d05a372d0&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 179362 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=536260f7-3c9e-42db-be44-9e19a7684c1a&collection.configName=conf1&name=testasynccollectioncreation&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 179479 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=536260f7-3c9e-42db-be44-9e19a7684c1a&collection.configName=conf1&name=testasynccollectioncreation&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=117
[junit4] 2> 179488 INFO (qtp1548992394-944) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=536260f7-3c9e-42db-be44-9e19a7684c1a&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 179554 INFO (qtp1548992394-944) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=536260f7-3c9e-42db-be44-9e19a7684c1a&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=66
[junit4] 2> 179634 INFO (OverseerCollectionConfigSetProcessor-75961824337788936-127.0.0.1:43558_solr-n_0000000000) [n:127.0.0.1:43558_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> 179634 INFO (OverseerThreadFactory-177-thread-2-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testasynccollectioncreation
[junit4] 2> 179708 ERROR (OverseerThreadFactory-177-thread-2-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: testasynccollectioncreation operation: create failed:org.apache.solr.common.SolrException: collection already exists: testasynccollectioncreation
[junit4] 2> at org.apache.solr.cloud.api.collections.CreateCollectionCmd.call(CreateCollectionCmd.java:116)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:263)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:504)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2>
[junit4] 2> 179846 INFO (OverseerThreadFactory-177-thread-2-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 180591 INFO (qtp1548992394-947) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=536260f7-3c9e-42db-be44-9e19a7684c1a&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 180644 INFO (qtp1548992394-947) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=536260f7-3c9e-42db-be44-9e19a7684c1a&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=53
[junit4] 2> 180674 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=536260f7-3c9e-42db-be44-9e19a7684c1a&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 180713 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=536260f7-3c9e-42db-be44-9e19a7684c1a&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=53
[junit4] 2> 180729 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params async=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f&action=ADDREPLICA&collection=testasynccollectioncreation&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 180880 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr c:testasynccollectioncreation ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f&action=ADDREPLICA&collection=testasynccollectioncreation&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=151
[junit4] 2> 180916 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 181050 INFO (OverseerCollectionConfigSetProcessor-75961824337788936-127.0.0.1:43558_solr-n_0000000000) [n:127.0.0.1:43558_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 181050 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=134
[junit4] 2> 181158 INFO (OverseerThreadFactory-177-thread-3-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:43558_solr for creating new replica of shard shard1 for collection testasynccollectioncreation
[junit4] 2> 181323 INFO (OverseerThreadFactory-177-thread-3-processing-n:127.0.0.1:43558_solr) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 181506 INFO (OverseerStateUpdate-75961824337788936-127.0.0.1:43558_solr-n_0000000000) [n:127.0.0.1:43558_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"testasynccollectioncreation",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testasynccollectioncreation_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43558/solr",
[junit4] 2> "node_name":"127.0.0.1:43558_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 181673 INFO (zkCallback-346-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 181673 INFO (zkCallback-346-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 181673 INFO (zkCallback-346-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 182068 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 182140 INFO (qtp1548992394-948) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=72
[junit4] 2> 182169 INFO (qtp1548992394-949) [n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400&qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&name=testasynccollectioncreation_shard1_replica_n3&action=CREATE&collection=testasynccollectioncreation&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=15
[junit4] 2> 182185 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command async=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400&qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&name=testasynccollectioncreation_shard1_replica_n3&action=CREATE&collection=testasynccollectioncreation&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 182185 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 182250 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400
[junit4] 2> 182270 INFO (qtp1548992394-950) [n:127.0.0.1:43558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=20
[junit4] 2> 182587 INFO (zkCallback-346-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 182587 INFO (zkCallback-346-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 182587 INFO (zkCallback-346-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testasynccollectioncreation/state.json] for collection [testasynccollectioncreation] has occurred - updating... (live nodes size: [2])
[junit4] 2> 182786 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 r:core_node4 x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 182875 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 r:core_node4 x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.s.IndexSchema [testasynccollectioncreation_shard1_replica_n3] Schema name=minimal
[junit4] 2> 182982 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 r:core_node4 x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 182982 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 r:core_node4 x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'testasynccollectioncreation_shard1_replica_n3' using configuration from collection testasynccollectioncreation, trusted=true
[junit4] 2> 182982 INFO (parallelCoreAdminExecutor-178-thread-1-processing-n:127.0.0.1:43558_solr x:testasynccollectioncreation_shard1_replica_n3 8411f34f-b76c-4ccf-a02c-aa9e1cfba06f59573840429360400 CREATE) [n:127.0.0.1:43558_solr c:testasynccollectioncreation s:shard1 r:core_node4 x:testasynccollectioncreation_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43558.solr.core.testasynccollectioncreation.shard1.replica_n3' (registry 'solr.core.testasynccollectioncreation.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@cd8d1b4
[junit4] 2> 1829
[...truncated too long message...]
clipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:500)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.run(HttpChannel.java:335)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> </pre>
[junit4] 2> <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.24.v20191120</a><hr/>
[junit4] 2>
[junit4] 2> </body>
[junit4] 2> </html>
[junit4] 2>
[junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.processErrorsAndResponse(Http2SolrClient.java:695) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:401) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:753) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.request(HttpShardHandler.java:234) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:195) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[?:?]
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:180) ~[metrics-core-4.1.2.jar:4.1.2]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:834) [?:?]
[junit4] 2> 248900 ERROR (OverseerThreadFactory-280-thread-2-processing-n:127.0.0.1:42338_solr) [n:127.0.0.1:42338_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard 127.0.0.1:34003_solr: {STATUS=failed}
[junit4] 2> 249011 INFO (OverseerThreadFactory-280-thread-2-processing-n:127.0.0.1:42338_solr) [n:127.0.0.1:42338_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 249025 INFO (closeThreadPool-493-thread-2) [ ] o.a.s.c.Overseer Overseer (id=75961828806885384-127.0.0.1:42338_solr-n_0000000000) closing
[junit4] 2> 249074 INFO (OverseerStateUpdate-75961828806885384-127.0.0.1:42338_solr-n_0000000000) [n:127.0.0.1:42338_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42338_solr
[junit4] 2> 249130 INFO (OverseerAutoScalingTriggerThread-75961828806885384-127.0.0.1:42338_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 249225 INFO (closeThreadPool-493-thread-1) [ ] o.a.s.c.Overseer Overseer (id=75961828806885384-127.0.0.1:42338_solr-n_0000000000) closing
[junit4] 2> 249243 INFO (coreCloseExecutor-295-thread-1) [n:127.0.0.1:34003_solr c:testAsyncIdRaceCondition s:shard1 r:core_node2 x:testAsyncIdRaceCondition_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testAsyncIdRaceCondition.shard1.leader, tag=SolrCore@2023767
[junit4] 2> 249419 INFO (jetty-closer-486-thread-1) [ ] o.a.s.c.Overseer Overseer (id=75961828806885384-127.0.0.1:42338_solr-n_0000000000) closing
[junit4] 2> 249465 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 249482 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7e9bd1cb: rootName = solr_34003, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3be98e28
[junit4] 2> 249496 INFO (zkCallback-462-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 249517 INFO (jetty-closer-486-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5f221e55{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:০}
[junit4] 2> 249550 INFO (jetty-closer-486-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@92b4533{/solr,null,UNAVAILABLE}
[junit4] 2> 249550 INFO (jetty-closer-486-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 249567 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 249567 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2960c6b7: rootName = solr_34003, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@10f8722
[junit4] 2> 249623 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 249623 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@18d7a67d: rootName = solr_34003, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@a04f013
[junit4] 2> 249623 INFO (jetty-closer-486-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 249935 INFO (jetty-closer-486-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@436f378d{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:০}
[junit4] 2> 249952 INFO (jetty-closer-486-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2bce217d{/solr,null,UNAVAILABLE}
[junit4] 2> 249952 INFO (jetty-closer-486-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 250041 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 250379 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/testAsyncIdRaceCondition/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/testAsyncIdRaceCondition/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/overseer/queue
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 250382 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38394
[junit4] 2> 250382 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38394
[junit4] 2> 250382 INFO (TEST-CollectionsAPIAsyncDistributedZkTest.testAsyncIdRaceCondition-seed#[6BCA0FF14F222948]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38394
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.cloud.api.collections.CollectionsAPIAsyncDistributedZkTest_6BCA0FF14F222948-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {_root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=PostingsFormat(name=LuceneVarGapDocFreqInterval), _route_=PostingsFormat(name=Direct)}, docValues:{_version_=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1863, maxMBSortInHeap=7.676166623070177, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6ecb81c2), locale=as, timezone=America/Indiana/Indianapolis
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=4,threads=1,free=169969008,total=308281344
[junit4] 2> NOTE: All tests run in this JVM: [DocValuesMissingTest, XCJFQueryTest, TestChildDocTransformerHierarchy, CollectionsAPIAsyncDistributedZkTest]
[junit4] Completed [15/894 (1!)] on J0 in 91.57s, 4 tests, 1 failure <<< FAILURES!
[...truncated 55945 lines...]