Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2880/
Java: 32bit/jdk-9-ea+155 -server -XX:+UseG1GC

2 tests failed.
FAILED:  
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI

Error Message:
expected:<3> but was:<1>

Stack Trace:
java.lang.AssertionError: expected:<3> but was:<1>
        at 
__randomizedtesting.SeedInfo.seed([3205351A035A8EDD:7A7041AE0569A148]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:522)
        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:543)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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:844)


FAILED:  org.apache.solr.handler.admin.TestApiFramework.testFramework

Error Message:


Stack Trace:
java.lang.ExceptionInInitializerError
        at 
__randomizedtesting.SeedInfo.seed([3205351A035A8EDD:2573FF3D058E62E0]:0)
        at 
net.sf.cglib.core.KeyFactory$Generator.generateClass(KeyFactory.java:166)
        at 
net.sf.cglib.core.DefaultGeneratorStrategy.generate(DefaultGeneratorStrategy.java:25)
        at 
net.sf.cglib.core.AbstractClassGenerator.create(AbstractClassGenerator.java:216)
        at net.sf.cglib.core.KeyFactory$Generator.create(KeyFactory.java:144)
        at net.sf.cglib.core.KeyFactory.create(KeyFactory.java:116)
        at net.sf.cglib.core.KeyFactory.create(KeyFactory.java:108)
        at net.sf.cglib.core.KeyFactory.create(KeyFactory.java:104)
        at net.sf.cglib.proxy.Enhancer.<clinit>(Enhancer.java:69)
        at 
org.easymock.internal.ClassProxyFactory.createEnhancer(ClassProxyFactory.java:259)
        at 
org.easymock.internal.ClassProxyFactory.createProxy(ClassProxyFactory.java:174)
        at org.easymock.internal.MocksControl.createMock(MocksControl.java:60)
        at org.easymock.EasyMock.createMock(EasyMock.java:104)
        at 
org.apache.solr.handler.admin.TestCoreAdminApis.getCoreContainerMock(TestCoreAdminApis.java:83)
        at 
org.apache.solr.handler.admin.TestApiFramework.testFramework(TestApiFramework.java:59)
        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:543)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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:844)
Caused by: java.lang.reflect.InaccessibleObjectException: Unable to make 
protected final java.lang.Class 
java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
 throws java.lang.ClassFormatError accessible: module java.base does not "opens 
java.lang" to unnamed module @db8401
        at 
java.base/java.lang.reflect.AccessibleObject.checkCanSetAccessible(AccessibleObject.java:207)
        at 
java.base/java.lang.reflect.Method.checkCanSetAccessible(Method.java:192)
        at java.base/java.lang.reflect.Method.setAccessible(Method.java:186)
        at net.sf.cglib.core.ReflectUtils$2.run(ReflectUtils.java:56)
        at java.base/java.security.AccessController.doPrivileged(Native Method)
        at net.sf.cglib.core.ReflectUtils.<clinit>(ReflectUtils.java:46)
        ... 52 more




Build Log:
[...truncated 11212 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/init-core-data-001
   [junit4]   2> 139346 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 139348 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 139350 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001
   [junit4]   2> 139351 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 139351 INFO  (Thread-146) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 139351 INFO  (Thread-146) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 139451 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:33292
   [junit4]   2> 139463 INFO  (jetty-launcher-178-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 139465 INFO  (jetty-launcher-178-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 139465 INFO  (jetty-launcher-178-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 139465 INFO  (jetty-launcher-178-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 139470 INFO  (jetty-launcher-178-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1c11489{/solr,null,AVAILABLE}
   [junit4]   2> 139472 INFO  (jetty-launcher-178-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@7f85d1{SSL,[ssl, 
http/1.1]}{127.0.0.1:43568}
   [junit4]   2> 139472 INFO  (jetty-launcher-178-thread-2) [    ] 
o.e.j.s.Server Started @141546ms
   [junit4]   2> 139472 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43568}
   [junit4]   2> 139473 ERROR (jetty-launcher-178-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 139473 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 139473 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 139473 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 139473 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-18T00:29:36.106287Z
   [junit4]   2> 139474 INFO  (jetty-launcher-178-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@80e72b{/solr,null,AVAILABLE}
   [junit4]   2> 139474 INFO  (jetty-launcher-178-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@274a73{/solr,null,AVAILABLE}
   [junit4]   2> 139474 INFO  (jetty-launcher-178-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@11d5aab{/solr,null,AVAILABLE}
   [junit4]   2> 139474 INFO  (jetty-launcher-178-thread-3) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@d4b142{SSL,[ssl, 
http/1.1]}{127.0.0.1:36644}
   [junit4]   2> 139474 INFO  (jetty-launcher-178-thread-3) [    ] 
o.e.j.s.Server Started @141548ms
   [junit4]   2> 139474 INFO  (jetty-launcher-178-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1bc362a{SSL,[ssl, 
http/1.1]}{127.0.0.1:43369}
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=36644}
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-4) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@b1f133{SSL,[ssl, 
http/1.1]}{127.0.0.1:34194}
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-1) [    ] 
o.e.j.s.Server Started @141548ms
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43369}
   [junit4]   2> 139475 ERROR (jetty-launcher-178-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 139475 ERROR (jetty-launcher-178-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-4) [    ] 
o.e.j.s.Server Started @141549ms
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=34194}
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-18T00:29:36.108608Z
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-18T00:29:36.108705Z
   [junit4]   2> 139475 ERROR (jetty-launcher-178-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 139475 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 139476 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-18T00:29:36.109018Z
   [junit4]   2> 139480 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139480 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139480 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139483 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 139486 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 139487 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33292/solr
   [junit4]   2> 139493 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 139496 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 139498 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 139500 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33292/solr
   [junit4]   2> 139505 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33292/solr
   [junit4]   2> 139506 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33292/solr
   [junit4]   2> 139508 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139509 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43369_solr
   [junit4]   2> 139510 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=97476789926952966-127.0.0.1:43369_solr-n_0000000000) starting
   [junit4]   2> 139531 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139532 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:36644_solr
   [junit4]   2> 139533 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139540 INFO  (zkCallback-189-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 139543 INFO  (zkCallback-198-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 139572 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 139573 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43568_solr
   [junit4]   2> 139575 INFO  (zkCallback-198-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 139575 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 139581 INFO  (zkCallback-189-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 139583 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43369_solr
   [junit4]   2> 139583 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 139584 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 139584 INFO  (zkCallback-198-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 139585 INFO  (zkCallback-189-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 139585 INFO  (zkCallback-200-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 139588 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 139589 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:34194_solr
   [junit4]   2> 139591 INFO  (zkCallback-189-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 139591 INFO  (zkCallback-200-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 139591 INFO  (zkCallback-198-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 139591 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 139834 INFO  (jetty-launcher-178-thread-1) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/.
   [junit4]   2> 139836 INFO  (jetty-launcher-178-thread-4) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node4/.
   [junit4]   2> 139970 INFO  (jetty-launcher-178-thread-2) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/.
   [junit4]   2> 139988 INFO  (jetty-launcher-178-thread-3) [    ] 
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node3/.
   [junit4]   2> 140108 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 140108 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33292/solr ready
   [junit4]   2> 140155 INFO  
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testBadActionNames
   [junit4]   2> 140162 INFO  
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[3205351A035A8EDD])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 140232 ERROR (qtp16254825-931) [    ] 
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Unknown 
action: BADACTION
   [junit4]   2>        at 
org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:208)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:171)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:748)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:729)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:510)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:347)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:298)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:202)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2>        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 
   [junit4]   2> 140232 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={name=badactioncollection&action=BADACTION&numShards=2&wt=javabin&version=2}
 status=400 QTime=0
   [junit4]   2> 140233 INFO  
(TEST-CollectionsAPIDistributedZkTest.testBadActionNames-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testBadActionNames
   [junit4]   2> 140257 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCreateNodeSet
   [junit4]   2> 140259 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[3205351A035A8EDD])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 140264 INFO  (qtp16254825-901) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=https://127.0.0.1:36644/solr,https://127.0.0.1:43369/solr&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 140266 INFO  (OverseerThreadFactory-399-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd Create collection nodeset_collection
   [junit4]   2> 140267 WARN  (OverseerThreadFactory-399-thread-1) [    ] 
o.a.s.c.CreateCollectionCmd It is unusual to create a collection 
(nodeset_collection) without cores.
   [junit4]   2> 140378 INFO  (qtp16254825-901) [    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 140378 INFO  (qtp16254825-901) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=nodeset_collection&action=CREATE&numShards=2&createNodeSet=https://127.0.0.1:36644/solr,https://127.0.0.1:43369/solr&wt=javabin&version=2}
 status=0 QTime=114
   [junit4]   2> 140379 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCreateNodeSet-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testCreateNodeSet
   [junit4]   2> 140406 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testNoCollectionSpecified
   [junit4]   2> 140407 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[3205351A035A8EDD])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 140410 INFO  (qtp16254825-922) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=nodeset_collection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 140412 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] 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> 140412 INFO  (OverseerThreadFactory-399-thread-2) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : 
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 141115 INFO  (qtp16254825-922) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={name=nodeset_collection&action=DELETE&wt=javabin&version=2} status=0 
QTime=705
   [junit4]   2> 141120 INFO  (qtp16254825-926) [    ] o.a.s.u.TestInjection 
Inject random core creation delay of 9s
   [junit4]   2> 142413 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 150120 INFO  (qtp16254825-926) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
dataDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-002&collection.configName=conf&name=corewithnocollection&action=CREATE&numShards=1&collection=&wt=javabin&version=2
   [junit4]   2> 150122 INFO  
(OverseerStateUpdate-97476789926952966-127.0.0.1:43369_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 151128 INFO  (qtp16254825-926) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 151134 INFO  (qtp16254825-926) [    ] o.a.s.s.IndexSchema 
[corewithnocollection] Schema name=minimal
   [junit4]   2> 151135 INFO  (qtp16254825-926) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 151136 INFO  (qtp16254825-926) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'corewithnocollection' using configuration from collection 
corewithnocollection
   [junit4]   2> 151136 INFO  (qtp16254825-926) [    ] o.a.s.c.SolrCore 
[[corewithnocollection] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/corewithnocollection],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-002/]
   [junit4]   2> 151402 INFO  (qtp16254825-926) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 151402 INFO  (qtp16254825-926) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 151403 INFO  (qtp16254825-926) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 151403 INFO  (qtp16254825-926) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 151404 INFO  (qtp16254825-926) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a0405b[corewithnocollection] main]
   [junit4]   2> 151404 INFO  (qtp16254825-926) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 151404 INFO  (qtp16254825-926) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 151405 INFO  (qtp16254825-926) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 151405 INFO  (searcherExecutor-408-thread-1) [    ] 
o.a.s.c.SolrCore [corewithnocollection] Registered new searcher 
Searcher@1a0405b[corewithnocollection] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 151405 INFO  (qtp16254825-926) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1559628651469733888
   [junit4]   2> 151410 INFO  (qtp16254825-926) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 151410 INFO  (qtp16254825-926) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 151410 INFO  (qtp16254825-926) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:43369/solr/corewithnocollection/
   [junit4]   2> 151410 INFO  (qtp16254825-926) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 151410 INFO  (qtp16254825-926) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:43369/solr/corewithnocollection/ has no replicas
   [junit4]   2> 151412 INFO  (qtp16254825-926) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:43369/solr/corewithnocollection/ shard1
   [junit4]   2> 151563 INFO  (qtp16254825-926) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 151565 INFO  (qtp16254825-926) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={dataDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-002&collection.configName=conf&name=corewithnocollection&action=CREATE&numShards=1&collection=&wt=javabin&version=2}
 status=0 QTime=10445
   [junit4]   2> 151568 INFO  (qtp16254825-931) [    ] o.a.s.u.TestInjection 
Inject random core creation delay of 9s
   [junit4]   2> 160568 INFO  (qtp16254825-931) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
dataDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-002&collection.configName=conf&name=corewithnocollection2&action=CREATE&numShards=1&wt=javabin&version=2
   [junit4]   2> 160570 INFO  
(OverseerStateUpdate-97476789926952966-127.0.0.1:43369_solr-n_0000000000) [    
] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 161577 INFO  (qtp16254825-931) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 161585 INFO  (qtp16254825-931) [    ] o.a.s.s.IndexSchema 
[corewithnocollection2] Schema name=minimal
   [junit4]   2> 161586 INFO  (qtp16254825-931) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 161587 INFO  (qtp16254825-931) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'corewithnocollection2' using configuration from collection 
corewithnocollection2
   [junit4]   2> 161587 INFO  (qtp16254825-931) [    ] o.a.s.c.SolrCore 
[[corewithnocollection2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/corewithnocollection2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-002/]
   [junit4]   2> 161968 INFO  (qtp16254825-931) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 161968 INFO  (qtp16254825-931) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 161970 INFO  (qtp16254825-931) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 161970 INFO  (qtp16254825-931) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 161970 INFO  (qtp16254825-931) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@686421[corewithnocollection2] main]
   [junit4]   2> 161971 INFO  (qtp16254825-931) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 161971 INFO  (qtp16254825-931) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 161971 INFO  (qtp16254825-931) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 161972 INFO  (searcherExecutor-413-thread-1) [    ] 
o.a.s.c.SolrCore [corewithnocollection2] Registered new searcher 
Searcher@686421[corewithnocollection2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 161972 INFO  (qtp16254825-931) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1559628662550036480
   [junit4]   2> 161976 INFO  (qtp16254825-931) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 161976 INFO  (qtp16254825-931) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 161976 INFO  (qtp16254825-931) [    ] o.a.s.c.SyncStrategy 
Sync replicas to https://127.0.0.1:43369/solr/corewithnocollection2/
   [junit4]   2> 161976 INFO  (qtp16254825-931) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 161976 INFO  (qtp16254825-931) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:43369/solr/corewithnocollection2/ has no replicas
   [junit4]   2> 161978 INFO  (qtp16254825-931) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:43369/solr/corewithnocollection2/ shard1
   [junit4]   2> 162128 INFO  (qtp16254825-931) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 162130 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={dataDir=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-002&collection.configName=conf&name=corewithnocollection2&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=10562
   [junit4]   2> 162131 INFO  
(TEST-CollectionsAPIDistributedZkTest.testNoCollectionSpecified-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testNoCollectionSpecified
   [junit4]   2> 162155 INFO  
(TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteNonExistentCollection
   [junit4]   2> 162156 INFO  
(TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[3205351A035A8EDD])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 162160 INFO  (qtp16254825-901) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=corewithnocollection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 162162 INFO  (OverseerThreadFactory-399-thread-3) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : 
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 162181 INFO  (qtp16254825-931) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection
   [junit4]   2> 162181 INFO  (qtp16254825-931) [    ] o.a.s.c.SolrCore 
[corewithnocollection]  CLOSING SolrCore org.apache.solr.core.SolrCore@124305d
   [junit4]   2> 162184 INFO  (qtp16254825-931) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection
   [junit4]   2> 162187 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=corewithnocollection&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=7
   [junit4]   2> 162892 INFO  (qtp16254825-901) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={name=corewithnocollection&action=DELETE&wt=javabin&version=2} status=0 
QTime=732
   [junit4]   2> 162895 INFO  (qtp16254825-922) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=corewithnocollection2&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 162897 INFO  (OverseerThreadFactory-399-thread-4) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : 
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 162897 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 162899 INFO  (qtp16254825-926) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection2
   [junit4]   2> 162900 INFO  (qtp16254825-926) [    ] o.a.s.c.SolrCore 
[corewithnocollection2]  CLOSING SolrCore org.apache.solr.core.SolrCore@6ebd4d
   [junit4]   2> 162902 INFO  (qtp16254825-926) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.corewithnocollection2
   [junit4]   2> 162911 INFO  (qtp16254825-926) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=corewithnocollection2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=11
   [junit4]   2> 163614 INFO  (qtp16254825-922) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={name=corewithnocollection2&action=DELETE&wt=javabin&version=2} status=0 
QTime=719
   [junit4]   2> 163618 INFO  (qtp16254825-904) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=unknown_collection&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 163620 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 163620 INFO  (OverseerThreadFactory-399-thread-5) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : 
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 163621 ERROR (OverseerThreadFactory-399-thread-5) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Collection: unknown_collection 
operation: delete failed:org.apache.solr.common.SolrException: Could not find 
collection : unknown_collection
   [junit4]   2>        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:194)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerCollectionMessageHandler.collectionCmd(OverseerCollectionMessageHandler.java:843)
   [junit4]   2>        at 
org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:88)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:224)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:463)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2>        at 
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
   [junit4]   2>        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
   [junit4]   2>        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 
   [junit4]   2> 163626 INFO  (qtp16254825-904) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={name=unknown_collection&action=DELETE&wt=javabin&version=2} status=400 
QTime=8
   [junit4]   2> 163629 INFO  (qtp16254825-901) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=2&collection.configName=conf&name=acollectionafterbaddelete&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 163631 INFO  (OverseerThreadFactory-399-thread-5) [    ] 
o.a.s.c.CreateCollectionCmd Create collection acollectionafterbaddelete
   [junit4]   2> 163631 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 163839 INFO  (qtp16254825-931) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica1&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2
   [junit4]   2> 163906 INFO  (qtp20164321-899) [    ] o.a.s.u.TestInjection 
Inject random core creation delay of 9s
   [junit4]   2> 163947 INFO  (zkCallback-189-thread-6) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 164850 INFO  (qtp16254825-931) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 164867 INFO  (qtp16254825-931) [    ] o.a.s.s.IndexSchema 
[acollectionafterbaddelete_shard1_replica1] Schema name=minimal
   [junit4]   2> 164869 INFO  (qtp16254825-931) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 164870 INFO  (qtp16254825-931) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'acollectionafterbaddelete_shard1_replica1' using 
configuration from collection acollectionafterbaddelete
   [junit4]   2> 164871 INFO  (qtp16254825-931) [    ] o.a.s.c.SolrCore 
[[acollectionafterbaddelete_shard1_replica1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/acollectionafterbaddelete_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/./acollectionafterbaddelete_shard1_replica1/data/]
   [junit4]   2> 165142 INFO  (qtp16254825-931) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 165142 INFO  (qtp16254825-931) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 165145 INFO  (qtp16254825-931) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 165145 INFO  (qtp16254825-931) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 165145 INFO  (qtp16254825-931) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@a90daf[acollectionafterbaddelete_shard1_replica1] main]
   [junit4]   2> 165146 INFO  (qtp16254825-931) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 165146 INFO  (qtp16254825-931) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 165146 INFO  (qtp16254825-931) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 165147 INFO  (searcherExecutor-418-thread-1) [    ] 
o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica1] Registered new 
searcher Searcher@a90daf[acollectionafterbaddelete_shard1_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 165147 INFO  (qtp16254825-931) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1559628665879265280
   [junit4]   2> 165152 INFO  (qtp16254825-931) [    ] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 165152 INFO  (qtp16254825-931) [    ] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 165152 INFO  (qtp16254825-931) [    ] o.a.s.c.SyncStrategy 
Sync replicas to 
https://127.0.0.1:43369/solr/acollectionafterbaddelete_shard1_replica1/
   [junit4]   2> 165152 INFO  (qtp16254825-931) [    ] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 165152 INFO  (qtp16254825-931) [    ] o.a.s.c.SyncStrategy 
https://127.0.0.1:43369/solr/acollectionafterbaddelete_shard1_replica1/ has no 
replicas
   [junit4]   2> 165154 INFO  (qtp16254825-931) [    ] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:43369/solr/acollectionafterbaddelete_shard1_replica1/ shard1
   [junit4]   2> 165267 INFO  (zkCallback-189-thread-4) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 165305 INFO  (qtp16254825-931) [    ] o.a.s.c.ZkController I 
am the leader, no recovery necessary
   [junit4]   2> 165308 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica1&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2}
 status=0 QTime=1469
   [junit4]   2> 165411 INFO  (zkCallback-189-thread-4) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 172906 INFO  (qtp20164321-899) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica2&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2
   [junit4]   2> 172909 INFO  (zkCallback-189-thread-6) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 172910 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 173917 INFO  (qtp20164321-899) [    ] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 6.5.0
   [junit4]   2> 173922 INFO  (qtp20164321-899) [    ] o.a.s.s.IndexSchema 
[acollectionafterbaddelete_shard1_replica2] Schema name=minimal
   [junit4]   2> 173924 INFO  (qtp20164321-899) [    ] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 173924 INFO  (qtp20164321-899) [    ] o.a.s.c.CoreContainer 
Creating SolrCore 'acollectionafterbaddelete_shard1_replica2' using 
configuration from collection acollectionafterbaddelete
   [junit4]   2> 173925 INFO  (qtp20164321-899) [    ] o.a.s.c.SolrCore 
[[acollectionafterbaddelete_shard1_replica2] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/acollectionafterbaddelete_shard1_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/./acollectionafterbaddelete_shard1_replica2/data/]
   [junit4]   2> 174226 INFO  (qtp20164321-899) [    ] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 174226 INFO  (qtp20164321-899) [    ] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 174230 INFO  (qtp20164321-899) [    ] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 174230 INFO  (qtp20164321-899) [    ] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 174232 INFO  (qtp20164321-899) [    ] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1bc4131[acollectionafterbaddelete_shard1_replica2] main]
   [junit4]   2> 174233 INFO  (qtp20164321-899) [    ] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 174233 INFO  (qtp20164321-899) [    ] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 174233 INFO  (qtp20164321-899) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 174234 INFO  (searcherExecutor-423-thread-1) [    ] 
o.a.s.c.SolrCore [acollectionafterbaddelete_shard1_replica2] Registered new 
searcher Searcher@1bc4131[acollectionafterbaddelete_shard1_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 174234 INFO  (qtp20164321-899) [    ] o.a.s.u.UpdateLog Could 
not find max version in index or recent updates, using new clock 
1559628675407675392
   [junit4]   2> 174240 INFO  (qtp20164321-899) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=acollectionafterbaddelete_shard1_replica2&action=CREATE&numShards=1&collection=acollectionafterbaddelete&shard=shard1&wt=javabin&version=2}
 status=0 QTime=10334
   [junit4]   2> 174242 INFO  (qtp16254825-901) [    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 174340 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 174340 INFO  (zkCallback-189-thread-4) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/acollectionafterbaddelete/state.json] for collection 
[acollectionafterbaddelete] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 175242 INFO  (qtp16254825-901) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={replicationFactor=2&collection.configName=conf&name=acollectionafterbaddelete&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=11613
   [junit4]   2> 175246 INFO  
(TEST-CollectionsAPIDistributedZkTest.testDeleteNonExistentCollection-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteNonExistentCollection
   [junit4]   2> 175292 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[3205351A035A8EDD])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollectionsAPI
   [junit4]   2> 175292 INFO  
(TEST-CollectionsAPIDistributedZkTest.testCollectionsAPI-seed#[3205351A035A8EDD])
 [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 175295 INFO  (qtp16254825-926) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
name=acollectionafterbaddelete&action=DELETE&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 175296 INFO  (OverseerThreadFactory-399-thread-5) [    ] 
o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : 
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
   [junit4]   2> 175296 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000010 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 175307 INFO  (qtp20164321-918) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.acollectionafterbaddelete.shard1.replica2
   [junit4]   2> 175307 INFO  (qtp16254825-922) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.acollectionafterbaddelete.shard1.replica1
   [junit4]   2> 175307 INFO  (qtp20164321-918) [    ] o.a.s.c.SolrCore 
[acollectionafterbaddelete_shard1_replica2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4fa806
   [junit4]   2> 175307 INFO  (qtp16254825-922) [    ] o.a.s.c.SolrCore 
[acollectionafterbaddelete_shard1_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@16fc095
   [junit4]   2> 175310 INFO  (qtp16254825-922) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.acollectionafterbaddelete.shard1.replica1
   [junit4]   2> 175310 INFO  (qtp20164321-918) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.acollectionafterbaddelete.shard1.replica2
   [junit4]   2> 175316 INFO  (qtp20164321-918) [    ] 
o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader 
parent node, won't remove previous leader registration.
   [junit4]   2> 175317 INFO  (qtp20164321-918) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=acollectionafterbaddelete_shard1_replica2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=14
   [junit4]   2> 175318 WARN  (zkCallback-199-thread-1) [    ] 
o.a.s.c.LeaderElector Our node is no longer in line to be leader
   [junit4]   2> 175319 INFO  (qtp16254825-922) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=acollectionafterbaddelete_shard1_replica1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=19
   [junit4]   2> 176024 INFO  (qtp16254825-926) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={name=acollectionafterbaddelete&action=DELETE&wt=javabin&version=2} 
status=0 QTime=728
   [junit4]   2> 176027 INFO  (qtp16254825-931) [    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
async=bc6d202a-2322-432c-9400-935a3d43df12&replicationFactor=3&collection.configName=conf2&maxShardsPerNode=4&name=awhollynewcollection_0&action=CREATE&numShards=4&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 176028 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/collections 
params={async=bc6d202a-2322-432c-9400-935a3d43df12&replicationFactor=3&collection.configName=conf2&maxShardsPerNode=4&name=awhollynewcollection_0&action=CREATE&numShards=4&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 176030 INFO  (OverseerThreadFactory-399-thread-5) [    ] 
o.a.s.c.CreateCollectionCmd Create collection awhollynewcollection_0
   [junit4]   2> 176031 INFO  
(OverseerCollectionConfigSetProcessor-97476789926952966-127.0.0.1:43369_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000012 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 176136 INFO  (zkCallback-207-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeCreated 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 176250 INFO  (qtp20164321-923) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298124508014&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard4_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard4&wt=javabin&version=2}
 status=0 QTime=12
   [junit4]   2> 176250 INFO  (qtp16254825-904) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298124371644&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard3_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard3&wt=javabin&version=2}
 status=0 QTime=13
   [junit4]   2> 176253 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176253 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176316 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298124765824&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2}
 status=0 QTime=43
   [junit4]   2> 176316 INFO  (qtp20164321-928) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298139167107&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2}
 status=0 QTime=61
   [junit4]   2> 176316 INFO  (qtp16254825-909) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2}
 status=0 QTime=42
   [junit4]   2> 176316 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176317 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176320 INFO  (qtp20164321-923) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298137708685&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard3_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard3&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 176320 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176321 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176353 INFO  (qtp14040384-1054) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298124881651&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 176354 INFO  (parallelCoreAdminExecutor-405-thread-1) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176374 INFO  (qtp16304924-1057) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298126903563&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2}
 status=0 QTime=6
   [junit4]   2> 176378 INFO  (parallelCoreAdminExecutor-401-thread-1) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176384 INFO  (qtp16304924-924) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298124588017&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard4_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard4&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 176384 INFO  (parallelCoreAdminExecutor-401-thread-2) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176384 INFO  (qtp16304924-908) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298133938533&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard3_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard3&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 176384 INFO  (parallelCoreAdminExecutor-401-thread-3) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176385 INFO  (qtp14040384-1054) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298133779949&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 176386 INFO  (parallelCoreAdminExecutor-405-thread-2) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176386 INFO  (qtp14040384-1055) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={async=bc6d202a-2322-432c-9400-935a3d43df126084298137133597&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard4_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard4&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 176387 INFO  (parallelCoreAdminExecutor-405-thread-3) [    ] 
o.a.s.u.TestInjection Inject random core creation delay of 9s
   [junit4]   2> 176389 INFO  (qtp16254825-912) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 176389 INFO  (qtp16254825-912) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 177392 INFO  (qtp16254825-904) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 177392 INFO  (qtp16254825-904) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 178396 INFO  (qtp16254825-909) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 178396 INFO  (qtp16254825-909) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 179399 INFO  (qtp16254825-931) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 179399 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 180401 INFO  (qtp16254825-912) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 180402 INFO  (qtp16254825-912) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 181404 INFO  (qtp16254825-904) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 181404 INFO  (qtp16254825-904) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 182407 INFO  (qtp16254825-909) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 182408 INFO  (qtp16254825-909) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 183411 INFO  (qtp16254825-931) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 183411 INFO  (qtp16254825-931) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 184414 INFO  (qtp16254825-912) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 184414 INFO  (qtp16254825-912) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 185253 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298124508014&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard4_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard4&wt=javabin&version=2
   [junit4]   2> 185253 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298124371644&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard3_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard3&wt=javabin&version=2
   [junit4]   2> 185255 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185255 INFO  (zkCallback-189-thread-4) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185255 INFO  (zkCallback-207-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185316 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298139167107&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
   [junit4]   2> 185317 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298124765824&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
   [junit4]   2> 185320 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
   [junit4]   2> 185321 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298137708685&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard3_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard3&wt=javabin&version=2
   [junit4]   2> 185354 INFO  (parallelCoreAdminExecutor-405-thread-1) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298124881651&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
   [junit4]   2> 185380 INFO  (parallelCoreAdminExecutor-401-thread-1) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298126903563&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard1_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard1&wt=javabin&version=2
   [junit4]   2> 185384 INFO  (parallelCoreAdminExecutor-401-thread-2) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298124588017&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard4_replica2&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard4&wt=javabin&version=2
   [junit4]   2> 185385 INFO  (parallelCoreAdminExecutor-401-thread-3) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298133938533&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard3_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard3&wt=javabin&version=2
   [junit4]   2> 185386 INFO  (parallelCoreAdminExecutor-405-thread-2) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298133779949&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard2_replica3&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard2&wt=javabin&version=2
   [junit4]   2> 185387 INFO  (parallelCoreAdminExecutor-405-thread-3) [    ] 
o.a.s.h.a.CoreAdminOperation core create command 
async=bc6d202a-2322-432c-9400-935a3d43df126084298137133597&qt=/admin/cores&collection.configName=conf2&newCollection=true&name=awhollynewcollection_0_shard4_replica1&action=CREATE&numShards=4&collection=awhollynewcollection_0&shard=shard4&wt=javabin&version=2
   [junit4]   2> 185417 INFO  (qtp16254825-904) [    ] 
o.a.s.h.a.CoreAdminOperation Checking request status for : 
bc6d202a-2322-432c-9400-935a3d43df126084298127065036
   [junit4]   2> 185417 INFO  (qtp16254825-904) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=bc6d202a-2322-432c-9400-935a3d43df126084298127065036&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 185489 INFO  (zkCallback-189-thread-4) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185489 INFO  (zkCallback-198-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185489 INFO  (zkCallback-200-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185489 INFO  (zkCallback-207-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 185489 INFO  (zkCallback-199-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged 
path:/collections/awhollynewcollection_0/state.json] for collection 
[awhollynewcollection_0] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 186260 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186261 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186267 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard3_replica3] Schema name=minimal
   [junit4]   2> 186268 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard4_replica3] Schema name=minimal
   [junit4]   2> 186282 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard3_replica3' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard4_replica3' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.c.SolrCore [[awhollynewcollection_0_shard3_replica3] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/awhollynewcollection_0_shard3_replica3],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/./awhollynewcollection_0_shard3_replica3/data/]
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.c.SolrCore [[awhollynewcollection_0_shard4_replica3] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/awhollynewcollection_0_shard4_replica3],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/./awhollynewcollection_0_shard4_replica3/data/]
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-400-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@171c4bc
   [junit4]   2> 186283 INFO  (parallelCoreAdminExecutor-404-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@171c4bc
   [junit4]   2> 186323 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186324 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186331 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186352 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica1] Schema name=minimal
   [junit4]   2> 186353 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard3_replica2] Schema name=minimal
   [junit4]   2> 186353 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica1] Schema name=minimal
   [junit4]   2> 186355 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186355 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186356 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186356 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186356 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard1_replica1' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186356 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard2_replica1' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186356 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.c.SolrCore [[awhollynewcollection_0_shard1_replica1] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/awhollynewcollection_0_shard1_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/./awhollynewcollection_0_shard1_replica1/data/]
   [junit4]   2> 186356 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica1] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/awhollynewcollection_0_shard2_replica1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/./awhollynewcollection_0_shard2_replica1/data/]
   [junit4]   2> 186357 INFO  (parallelCoreAdminExecutor-400-thread-3) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@171c4bc
   [junit4]   2> 186357 INFO  (parallelCoreAdminExecutor-404-thread-2) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@171c4bc
   [junit4]   2> 186357 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard3_replica2' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186358 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.c.SolrCore [[awhollynewcollection_0_shard3_replica2] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/awhollynewcollection_0_shard3_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node2/./awhollynewcollection_0_shard3_replica2/data/]
   [junit4]   2> 186358 INFO  (parallelCoreAdminExecutor-404-thread-3) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@171c4bc
   [junit4]   2> 186365 INFO  (parallelCoreAdminExecutor-405-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 186366 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard2_replica2] Schema name=minimal
   [junit4]   2> 186368 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186369 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard2_replica2' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186369 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.c.SolrCore [[awhollynewcollection_0_shard2_replica2] ] Opening new 
SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/awhollynewcollection_0_shard2_replica2],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001/tempDir-001/node1/./awhollynewcollection_0_shard2_replica2/data/]
   [junit4]   2> 186370 INFO  (parallelCoreAdminExecutor-400-thread-2) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@171c4bc
   [junit4]   2> 186373 INFO  (parallelCoreAdminExecutor-405-thread-1) [    ] 
o.a.s.s.IndexSchema [awhollynewcollection_0_shard1_replica2] Schema name=minimal
   [junit4]   2> 186386 INFO  (parallelCoreAdminExecutor-405-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 186388 INFO  (parallelCoreAdminExecutor-405-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 
'awhollynewcollection_0_shard1_replica2' using configuration from collection 
awhollynewcollection_0
   [junit4]   2> 186388 INFO  (parallelCoreAdminExecutor-405-thr

[...truncated too long message...]

launcher-178-thread-7) [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1c11489{/solr,null,UNAVAILABLE}
   [junit4]   2> 365710 INFO  (jetty-launcher-178-thread-5) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@274a73{/solr,null,UNAVAILABLE}
   [junit4]   2> 365710 WARN  (zkCallback-200-thread-2) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 365711 INFO  (jetty-launcher-178-thread-6) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 365711 INFO  (jetty-launcher-178-thread-6) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@11d5aab{/solr,null,UNAVAILABLE}
   [junit4]   2> 365712 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:33292 33292
   [junit4]   2> 365715 INFO  (Thread-187) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:33292 33292
   [junit4]   2> 365716 WARN  (Thread-187) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        20      /solr/configs/conf
   [junit4]   2>        15      /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        172     /solr/clusterstate.json
   [junit4]   2>        15      /solr/clusterprops.json
   [junit4]   2>        4       
/solr/collections/nodes_used_collection/state.json
   [junit4]   2>        4       /solr/collections/addReplicaColl/state.json
   [junit4]   2>        4       /solr/collections/reloaded_collection/state.json
   [junit4]   2>        2       /solr/collections/onlyinzk/state.json
   [junit4]   2>        2       
/solr/collections/halfdeletedcollection/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        353     /solr/collections
   [junit4]   2>        90      /solr/overseer/queue
   [junit4]   2>        56      /solr/overseer/collection-queue-work
   [junit4]   2>        20      /solr/overseer/queue-work
   [junit4]   2>        15      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 365716 INFO  
(SUITE-CollectionsAPIDistributedZkTest-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_3205351A035A8EDD-001
   [junit4]   2> Feb 18, 2017 12:33:22 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene62, 
sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=sah, 
timezone=Asia/Macau
   [junit4]   2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=1,free=68450128,total=218103808
   [junit4]   2> NOTE: All tests run in this JVM: [CdcrVersionReplicationTest, 
SliceStateTest, TestRequestStatusCollectionAPI, SolrMetricReporterTest, 
OverseerModifyCollectionTest, IndexSchemaRuntimeFieldTest, 
OverseerTaskQueueTest, TestStressVersions, ClusterStateUpdateTest, 
SuggestComponentTest, ShufflingReplicaListTransformerTest, 
CollectionsAPIDistributedZkTest]
   [junit4] Completed [98/692 (1!)] on J1 in 227.14s, 20 tests, 1 failure <<< 
FAILURES!

[...truncated 185 lines...]
   [junit4] Suite: org.apache.solr.handler.admin.TestApiFramework
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.admin.TestApiFramework_3205351A035A8EDD-001/init-core-data-001
   [junit4]   2> 533001 INFO  
(SUITE-TestApiFramework-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 533003 INFO  
(SUITE-TestApiFramework-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 533004 INFO  
(TEST-TestApiFramework.testFramework-seed#[3205351A035A8EDD]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testFramework
   [junit4]   2> 533045 INFO  
(TEST-TestApiFramework.testFramework-seed#[3205351A035A8EDD]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testFramework
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestApiFramework 
-Dtests.method=testFramework -Dtests.seed=3205351A035A8EDD -Dtests.multiplier=3 
-Dtests.slow=true -Dtests.locale=ses-ML -Dtests.timezone=SystemV/EST5 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.04s J1 | TestApiFramework.testFramework <<<
   [junit4]    > Throwable #1: java.lang.ExceptionInInitializerError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3205351A035A8EDD:2573FF3D058E62E0]:0)
   [junit4]    >        at 
net.sf.cglib.core.KeyFactory$Generator.generateClass(KeyFactory.java:166)
   [junit4]    >        at 
net.sf.cglib.core.DefaultGeneratorStrategy.generate(DefaultGeneratorStrategy.java:25)
   [junit4]    >        at 
net.sf.cglib.core.AbstractClassGenerator.create(AbstractClassGenerator.java:216)
   [junit4]    >        at 
net.sf.cglib.core.KeyFactory$Generator.create(KeyFactory.java:144)
   [junit4]    >        at 
net.sf.cglib.core.KeyFactory.create(KeyFactory.java:116)
   [junit4]    >        at 
net.sf.cglib.core.KeyFactory.create(KeyFactory.java:108)
   [junit4]    >        at 
net.sf.cglib.core.KeyFactory.create(KeyFactory.java:104)
   [junit4]    >        at 
net.sf.cglib.proxy.Enhancer.<clinit>(Enhancer.java:69)
   [junit4]    >        at 
org.easymock.internal.ClassProxyFactory.createEnhancer(ClassProxyFactory.java:259)
   [junit4]    >        at 
org.easymock.internal.ClassProxyFactory.createProxy(ClassProxyFactory.java:174)
   [junit4]    >        at 
org.easymock.internal.MocksControl.createMock(MocksControl.java:60)
   [junit4]    >        at org.easymock.EasyMock.createMock(EasyMock.java:104)
   [junit4]    >        at 
org.apache.solr.handler.admin.TestCoreAdminApis.getCoreContainerMock(TestCoreAdminApis.java:83)
   [junit4]    >        at 
org.apache.solr.handler.admin.TestApiFramework.testFramework(TestApiFramework.java:59)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:543)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]    > Caused by: java.lang.reflect.InaccessibleObjectException: 
Unable to make protected final java.lang.Class 
java.lang.ClassLoader.defineClass(java.lang.String,byte[],int,int,java.security.ProtectionDomain)
 throws java.lang.ClassFormatError accessible: module java.base does not "opens 
java.lang" to unnamed module @db8401
   [junit4]    >        at 
java.base/java.lang.reflect.AccessibleObject.checkCanSetAccessible(AccessibleObject.java:207)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.checkCanSetAccessible(Method.java:192)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.setAccessible(Method.java:186)
   [junit4]    >        at 
net.sf.cglib.core.ReflectUtils$2.run(ReflectUtils.java:56)
   [junit4]    >        at 
java.base/java.security.AccessController.doPrivileged(Native Method)
   [junit4]    >        at 
net.sf.cglib.core.ReflectUtils.<clinit>(ReflectUtils.java:46)
   [junit4]    >        ... 52 more
   [junit4]   2> 533046 INFO  
(TEST-TestApiFramework.testTrailingTemplatePaths-seed#[3205351A035A8EDD]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testTrailingTemplatePaths
   [junit4]   2> 533047 INFO  
(TEST-TestApiFramework.testTrailingTemplatePaths-seed#[3205351A035A8EDD]) [    
] o.a.s.SolrTestCaseJ4 ###Ending testTrailingTemplatePaths
   [junit4]   2> 533047 INFO  
(SUITE-TestApiFramework-seed#[3205351A035A8EDD]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.admin.TestApiFramework_3205351A035A8EDD-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=304, maxMBSortInHeap=6.143200343918606, 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=ses-ML, 
timezone=SystemV/EST5
   [junit4]   2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=1,free=69975280,total=227540992
   [junit4]   2> NOTE: All tests run in this JVM: [CdcrVersionReplicationTest, 
SliceStateTest, TestRequestStatusCollectionAPI, SolrMetricReporterTest, 
OverseerModifyCollectionTest, IndexSchemaRuntimeFieldTest, 
OverseerTaskQueueTest, TestStressVersions, ClusterStateUpdateTest, 
SuggestComponentTest, ShufflingReplicaListTransformerTest, 
CollectionsAPIDistributedZkTest, DistributedTermsComponentTest, 
TestPartialUpdateDeduplication, TestRTGBase, DistanceFunctionTest, 
TestJsonRequest, TestCloudPseudoReturnFields, TestNumericTerms32, 
MigrateRouteKeyTest, SolrIndexConfigTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, TestSolrCloudWithKerberosAlt, 
SortByFunctionTest, IndexSchemaTest, CloneFieldUpdateProcessorFactoryTest, 
HighlighterTest, TestCloudDeleteByQuery, TestFieldCacheSanityChecker, 
TestQueryUtils, CdcrReplicationDistributedZkTest, TestSolrIndexConfig, 
BasicFunctionalityTest, SoftAutoCommitTest, 
DistribDocExpirationUpdateProcessorTest, SystemInfoHandlerTest, 
TestCoreContainer, TestShardHandlerFactory, TestCharFilters, 
TestReloadDeadlock, SampleTest, TestConfigSetsAPIZkFailure, 
TestClusterStateMutator, BlockJoinFacetDistribTest, HdfsSyncSliceTest, 
SolrGraphiteReporterTest, TestChildDocTransformer, TestApiFramework]
   [junit4] Completed [152/692 (2!)] on J1 in 0.06s, 2 tests, 1 error <<< 
FAILURES!

[...truncated 53412 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to