Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2219/

3 tests failed.
FAILED:  org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi

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

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([17139275D7534F06:403A69C00CA1AD1D]: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.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:724)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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 
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.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue

Error Message:
action wasn't interrupted

Stack Trace:
java.lang.AssertionError: action wasn't interrupted
        at 
__randomizedtesting.SeedInfo.seed([17139275D7534F06:DEA6D0DBDE3489F3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:705)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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 
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.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testOverwriteOption

Error Message:
Could not load collection from ZK: overwrite

Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: 
overwrite
        at 
__randomizedtesting.SeedInfo.seed([BA8883C80D40EB73:56B36223631094A5]:0)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1123)
        at 
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:648)
        at 
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:130)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testOverwriteOption(CloudSolrClientTest.java:155)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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 
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.lang.Thread.run(Thread.java:748)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for /collections/overwrite/state.json
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
        at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
        at 
org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
        at 
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1135)
        at 
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1121)
        ... 44 more




Build Log:
[...truncated 12358 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/init-core-data-001
   [junit4]   2> 1972668 WARN  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1972697 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1972698 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1972698 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1972699 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001
   [junit4]   2> 1972699 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1972757 INFO  (Thread-1211) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1972757 INFO  (Thread-1211) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1972826 ERROR (Thread-1211) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1972863 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:42583
   [junit4]   2> 1972928 INFO  (zkConnectionManagerCallback-1261-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1972965 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1972981 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.20.v20170531
   [junit4]   2> 1972982 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@8884682{/solr,null,AVAILABLE}
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6965318f{HTTP/1.1,[http/1.1]}{127.0.0.1:33767}
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.e.j.s.Server Started @1989224ms
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=33767}
   [junit4]   2> 1972984 ERROR (jetty-launcher-1258-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1972984 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-12-13T01:40:59.278Z
   [junit4]   2> 1972986 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7c310ad5{/solr,null,AVAILABLE}
   [junit4]   2> 1972986 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1f36dcc2{HTTP/1.1,[http/1.1]}{127.0.0.1:41979}
   [junit4]   2> 1972987 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.e.j.s.Server Started @1989227ms
   [junit4]   2> 1972987 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=41979}
   [junit4]   2> 1972987 ERROR (jetty-launcher-1258-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1972987 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1972987 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1972987 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1972987 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-12-13T01:40:59.281Z
   [junit4]   2> 1973042 INFO  (zkConnectionManagerCallback-1263-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1973043 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1973054 INFO  (zkConnectionManagerCallback-1265-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1973070 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1973099 INFO  (jetty-launcher-1258-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42583/solr
   [junit4]   2> 1973195 INFO  (jetty-launcher-1258-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42583/solr
   [junit4]   2> 1973237 INFO  (zkConnectionManagerCallback-1273-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1973270 INFO  (zkConnectionManagerCallback-1269-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1973283 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x1604d88302f0003, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1973355 INFO  
(zkConnectionManagerCallback-1275-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1973376 INFO  
(zkConnectionManagerCallback-1277-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1973762 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1973815 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:41979_solr
   [junit4]   2> 1973816 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.Overseer Overseer 
(id=99164439121428486-127.0.0.1:41979_solr-n_0000000000) starting
   [junit4]   2> 1973904 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:41979_solr
   [junit4]   2> 1973920 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1974227 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1974242 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1974243 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:33767_solr
   [junit4]   2> 1974289 INFO  
(zkCallback-1276-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1974329 INFO  
(zkCallback-1274-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 1974455 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41979.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1974495 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41979.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1974495 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_41979.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1974550 INFO  (jetty-launcher-1258-thread-2) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/.
   [junit4]   2> 1974914 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33767.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1974986 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33767.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1974986 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_33767.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1975004 INFO  (jetty-launcher-1258-thread-1) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/.
   [junit4]   2> 1975150 INFO  (zkConnectionManagerCallback-1282-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1975239 INFO  (zkConnectionManagerCallback-1286-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1975329 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1975402 INFO  
(SUITE-AutoScalingHandlerTest-seed#[17139275D7534F06]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1975849 INFO  
(TEST-AutoScalingHandlerTest.testReadApi-seed#[17139275D7534F06]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReadApi
   [junit4]   2> 1976138 INFO  
(zkConnectionManagerCallback-1290-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1976139 INFO  (qtp505155037-5643) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1976139 INFO  (qtp505155037-5643) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1976140 INFO  (qtp505155037-5641) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 1976175 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 1976194 INFO  (qtp505155037-5643) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=289
   [junit4]   2> 1976395 INFO  
(zkConnectionManagerCallback-1294-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1976396 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1976397 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1976556 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 1976578 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 
QTime=0
   [junit4]   2> 1976687 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=475
   [junit4]   2> 1976800 INFO  
(zkConnectionManagerCallback-1298-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1976813 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1976814 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1976857 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=41
   [junit4]   2> 1976888 INFO  (qtp522432878-5655) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=1
   [junit4]   2> 1976906 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=218
   [junit4]   2> 1976971 INFO  
(zkConnectionManagerCallback-1302-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1977048 INFO  (qtp505155037-5640) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1977122 INFO  (qtp505155037-5640) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1977143 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1977163 INFO  (qtp522432878-5658) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1977235 INFO  (qtp505155037-5640) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=328
   [junit4]   2> 1977288 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=52
   [junit4]   2> 1977353 INFO  
(zkConnectionManagerCallback-1306-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1977354 INFO  (qtp505155037-5643) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1977355 INFO  (qtp505155037-5643) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1977362 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1977448 INFO  (qtp522432878-5658) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1977504 INFO  (qtp505155037-5643) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics 
params={wt=javabin&version=2} status=0 QTime=199
   [junit4]   2> 1977512 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=6&collection.configName=conf&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1977724 INFO  
(OverseerThreadFactory-2205-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
readApiTestViolations
   [junit4]   2> 1977760 ERROR 
(OverseerThreadFactory-2205-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.OverseerCollectionMessageHandler 
Collection: readApiTestViolations operation: create 
failed:org.apache.solr.common.SolrException: 'maxShardsPerNode>0' is not 
supported when autoScaling policies are used
   [junit4]   2>        at 
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:137)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:232)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1978115 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=6&collection.configName=conf&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2}
 status=400 QTime=603
   [junit4]   2> 1978167 INFO  
(zkConnectionManagerCallback-1311-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1978196 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1978197 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1978198 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1978232 INFO  (qtp522432878-5658) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=33
   [junit4]   2> 1978269 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=152
   [junit4]   2> 1978270 INFO  (qtp505155037-5641) [n:127.0.0.1:33767_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=6&collection.configName=conf&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1978359 INFO  
(OverseerThreadFactory-2205-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
readApiTestViolations
   [junit4]   2> 1978395 INFO  
(OverseerCollectionConfigSetProcessor-99164439121428486-127.0.0.1:41979_solr-n_0000000000)
 [n:127.0.0.1:41979_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1978395 WARN  
(OverseerThreadFactory-2205-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.CreateCollectionCmd Specified number of 
replicas of 6 on collection readApiTestViolations is higher than the number of 
Solr instances currently live or live and part of your createNodeSet(2). It's 
unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 1978485 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1978506 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
 status=0 QTime=0
   [junit4]   2> 1978654 INFO  
(OverseerThreadFactory-2205-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.s.c.a.PolicyHelper returnSession, 
curr-time 13238574226 sessionWrapper.createTime 13238574078807652, 
this.sessionWrapper.createTime 13238574078807652 
   [junit4]   2> 1979269 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"readApiTestViolations",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"readApiTestViolations_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33767/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1979323 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"readApiTestViolations",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"readApiTestViolations_shard1_replica_n3",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:41979/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1979325 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"readApiTestViolations",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"readApiTestViolations_shard1_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33767/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1979359 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"readApiTestViolations",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"readApiTestViolations_shard1_replica_n5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:41979/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1979360 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"readApiTestViolations",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"readApiTestViolations_shard1_replica_n7",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:33767/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1979361 INFO  
(OverseerStateUpdate-99164439121428486-127.0.0.1:41979_solr-n_0000000000) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"readApiTestViolations",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"readApiTestViolations_shard1_replica_n8",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:41979/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1979582 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1979582 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1979592 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1979592 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1979593 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n4&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1979648 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n8&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1979650 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1979650 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n7&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1979826 INFO  
(zkCallback-1274-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1979826 INFO  
(zkCallback-1276-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1979842 INFO  
(zkCallback-1276-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1980827 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1980849 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1980863 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema 
[readApiTestViolations_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1980865 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 1980865 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.CoreContainer Creating 
SolrCore 'readApiTestViolations_shard1_replica_n1' using configuration from 
collection readApiTestViolations, trusted=true
   [junit4]   2> 1980866 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_33767.solr.core.readApiTestViolations.shard1.replica_n1' 
(registry 'solr.core.readApiTestViolations.shard1.replica_n1') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1980866 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1980866 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore 
[[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n1/data/]
   [junit4]   2> 1980885 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1981012 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1981031 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1981088 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema 
[readApiTestViolations_shard1_replica_n4] Schema name=minimal
   [junit4]   2> 1981104 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.s.IndexSchema 
[readApiTestViolations_shard1_replica_n5] Schema name=minimal
   [junit4]   2> 1981106 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 1981106 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.c.CoreContainer Creating 
SolrCore 'readApiTestViolations_shard1_replica_n5' using configuration from 
collection readApiTestViolations, trusted=true
   [junit4]   2> 1981160 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_41979.solr.core.readApiTestViolations.shard1.replica_n5' 
(registry 'solr.core.readApiTestViolations.shard1.replica_n5') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1981160 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1981160 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore 
[[readApiTestViolations_shard1_replica_n5] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n5],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n5/data/]
   [junit4]   2> 1981123 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 1981164 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.CoreContainer Creating 
SolrCore 'readApiTestViolations_shard1_replica_n4' using configuration from 
collection readApiTestViolations, trusted=true
   [junit4]   2> 1981164 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_33767.solr.core.readApiTestViolations.shard1.replica_n4' 
(registry 'solr.core.readApiTestViolations.shard1.replica_n4') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1981164 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1981164 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore 
[[readApiTestViolations_shard1_replica_n4] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n4],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n4/data/]
   [junit4]   2> 1981109 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.IndexSchema 
[readApiTestViolations_shard1_replica_n7] Schema name=minimal
   [junit4]   2> 1981177 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.IndexSchema 
[readApiTestViolations_shard1_replica_n8] Schema name=minimal
   [junit4]   2> 1981179 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 1981179 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.CoreContainer Creating 
SolrCore 'readApiTestViolations_shard1_replica_n8' using configuration from 
collection readApiTestViolations, trusted=true
   [junit4]   2> 1981182 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 1981182 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.CoreContainer Creating 
SolrCore 'readApiTestViolations_shard1_replica_n7' using configuration from 
collection readApiTestViolations, trusted=true
   [junit4]   2> 1981182 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_33767.solr.core.readApiTestViolations.shard1.replica_n7' 
(registry 'solr.core.readApiTestViolations.shard1.replica_n7') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1981182 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1981182 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrCore 
[[readApiTestViolations_shard1_replica_n7] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n7],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n7/data/]
   [junit4]   2> 1981213 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_41979.solr.core.readApiTestViolations.shard1.replica_n8' 
(registry 'solr.core.readApiTestViolations.shard1.replica_n8') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1981213 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1981213 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrCore 
[[readApiTestViolations_shard1_replica_n8] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n8],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n8/data/]
   [junit4]   2> 1981216 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 8.0.0
   [junit4]   2> 1981267 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.s.IndexSchema 
[readApiTestViolations_shard1_replica_n3] Schema name=minimal
   [junit4]   2> 1981286 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema 
minimal/1.1 with uniqueid field id
   [junit4]   2> 1981286 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.CoreContainer Creating 
SolrCore 'readApiTestViolations_shard1_replica_n3' using configuration from 
collection readApiTestViolations, trusted=true
   [junit4]   2> 1981316 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_41979.solr.core.readApiTestViolations.shard1.replica_n3' 
(registry 'solr.core.readApiTestViolations.shard1.replica_n3') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ceebe5f
   [junit4]   2> 1981316 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1981316 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore 
[[readApiTestViolations_shard1_replica_n3] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n3],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_17139275D7534F06-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n3/data/]
   [junit4]   2> 1981782 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1981782 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1981783 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1981783 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1981785 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@73a4406[readApiTestViolations_shard1_replica_n1] main]
   [junit4]   2> 1981864 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1981864 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1981865 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 1981865 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1586631035489091584
   [junit4]   2> 1981939 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Waiting until we see more replicas up for shard shard1: total=6 found=1 
timeoutin=9999ms
   [junit4]   2> 1981962 INFO  
(searcherExecutor-2210-thread-1-processing-n:127.0.0.1:33767_solr 
x:readApiTestViolations_shard1_replica_n1 s:shard1 c:readApiTestViolations 
r:core_node2) [n:127.0.0.1:33767_solr c:readApiTestViolations s:shard1 
r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n1] Registered new searcher 
Searcher@73a4406[readApiTestViolations_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1982010 INFO  
(zkCallback-1276-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1982011 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1982011 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1982012 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1982012 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1982018 INFO  
(zkCallback-1274-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1982022 INFO  
(zkCallback-1276-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1982027 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7e11f311[readApiTestViolations_shard1_replica_n4] main]
   [junit4]   2> 1982028 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1982028 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1982029 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 1982169 INFO  
(searcherExecutor-2212-thread-1-processing-n:127.0.0.1:33767_solr 
x:readApiTestViolations_shard1_replica_n4 s:shard1 c:readApiTestViolations 
r:core_node9) [n:127.0.0.1:33767_solr c:readApiTestViolations s:shard1 
r:core_node9 x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n4] Registered new searcher 
Searcher@7e11f311[readApiTestViolations_shard1_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1982169 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1586631035807858688
   [junit4]   2> 1982297 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1982298 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1982314 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1982331 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1982351 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1982351 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1982352 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5736468f[readApiTestViolations_shard1_replica_n3] main]
   [junit4]   2> 1982353 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1982353 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1982354 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 1982367 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1586631036015476736
   [junit4]   2> 1982367 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1982367 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1982368 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1982368 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1982369 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5be8ea91[readApiTestViolations_shard1_replica_n8] main]
   [junit4]   2> 1982370 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1982370 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1982462 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1982462 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1982469 INFO  
(searcherExecutor-2215-thread-1-processing-n:127.0.0.1:41979_solr 
x:readApiTestViolations_shard1_replica_n3 s:shard1 c:readApiTestViolations 
r:core_node6) [n:127.0.0.1:41979_solr c:readApiTestViolations s:shard1 
r:core_node6 x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n3] Registered new searcher 
Searcher@5736468f[readApiTestViolations_shard1_replica_n3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1982477 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 1982477 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 1982478 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7e6e69b4[readApiTestViolations_shard1_replica_n5] main]
   [junit4]   2> 1982479 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4928e5eb[readApiTestViolations_shard1_replica_n7] main]
   [junit4]   2> 1982485 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1982485 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1982486 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 1982486 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1586631036140257280
   [junit4]   2> 1982516 INFO  
(searcherExecutor-2214-thread-1-processing-n:127.0.0.1:33767_solr 
x:readApiTestViolations_shard1_replica_n7 s:shard1 c:readApiTestViolations 
r:core_node11) [n:127.0.0.1:33767_solr c:readApiTestViolations s:shard1 
r:core_node11 x:readApiTestViolations_shard1_replica_n7] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n7] Registered new searcher 
Searcher@4928e5eb[readApiTestViolations_shard1_replica_n7] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1982516 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1982550 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1982551 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 1982551 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1586631036208414720
   [junit4]   2> 1982561 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1982619 INFO  
(searcherExecutor-2211-thread-1-processing-n:127.0.0.1:41979_solr 
x:readApiTestViolations_shard1_replica_n5 s:shard1 c:readApiTestViolations 
r:core_node10) [n:127.0.0.1:41979_solr c:readApiTestViolations s:shard1 
r:core_node10 x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n5] Registered new searcher 
Searcher@7e6e69b4[readApiTestViolations_shard1_replica_n5] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1982634 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1982635 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 1982635 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.UpdateLog Could not find max 
version in index or recent updates, using new clock 1586631036296495104
   [junit4]   2> 1982638 INFO  
(searcherExecutor-2213-thread-1-processing-n:127.0.0.1:41979_solr 
x:readApiTestViolations_shard1_replica_n8 s:shard1 c:readApiTestViolations 
r:core_node12) [n:127.0.0.1:41979_solr c:readApiTestViolations s:shard1 
r:core_node12 x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrCore 
[readApiTestViolations_shard1_replica_n8] Registered new searcher 
Searcher@5be8ea91[readApiTestViolations_shard1_replica_n8] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1983029 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Enough replicas found to continue.
   [junit4]   2> 1983029 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
may be the new leader - try and sync
   [junit4]   2> 1983029 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas 
to http://127.0.0.1:33767/solr/readApiTestViolations_shard1_replica_n1/
   [junit4]   2> 1983029 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: 
core=readApiTestViolations_shard1_replica_n1 url=http://127.0.0.1:33767/solr 
START 
replicas=[http://127.0.0.1:41979/solr/readApiTestViolations_shard1_replica_n3/, 
http://127.0.0.1:33767/solr/readApiTestViolations_shard1_replica_n4/, 
http://127.0.0.1:41979/solr/readApiTestViolations_shard1_replica_n5/, 
http://127.0.0.1:33767/solr/readApiTestViolations_shard1_replica_n7/, 
http://127.0.0.1:41979/solr/readApiTestViolations_shard1_replica_n8/] 
nUpdates=100
   [junit4]   2> 1983133 INFO  (qtp505155037-5640) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.S.Request 
[readApiTestViolations_shard1_replica_n4]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=75
   [junit4]   2> 1983133 INFO  (qtp522432878-5651) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.S.Request 
[readApiTestViolations_shard1_replica_n3]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=75
   [junit4]   2> 1983133 INFO  (qtp522432878-5657) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.c.S.Request 
[readApiTestViolations_shard1_replica_n5]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=75
   [junit4]   2> 1983206 INFO  (qtp522432878-5655) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.S.Request 
[readApiTestViolations_shard1_replica_n8]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=73
   [junit4]   2> 1983207 INFO  (qtp505155037-5753) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.c.S.Request 
[readApiTestViolations_shard1_replica_n7]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=55
   [junit4]   2> 1983402 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: 
core=readApiTestViolations_shard1_replica_n1 url=http://127.0.0.1:33767/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 1983402 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's 
attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 1983402 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext 
We failed sync, but we have no versions - we can't sync in that case - we were 
active before, so become leader anyway
   [junit4]   2> 1983402 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext 
Found all replicas participating in election, clear LIR
   [junit4]   2> 1983462 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I 
am the new leader: 
http://127.0.0.1:33767/solr/readApiTestViolations_shard1_replica_n1/ shard1
   [junit4]   2> 1983571 INFO  
(zkCallback-1274-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1983571 INFO  
(zkCallback-1276-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1983571 INFO  
(zkCallback-1276-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1983593 INFO  (qtp505155037-5647) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node11 
x:readApiTestViolations_shard1_replica_n7] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n7&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=3942
   [junit4]   2> 1983622 INFO  (qtp522432878-5652) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node6 
x:readApiTestViolations_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4029
   [junit4]   2> 1983626 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.ZkController I am the 
leader, no recovery necessary
   [junit4]   2> 1983628 INFO  (qtp505155037-5645) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node2 
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4045
   [junit4]   2> 1983665 INFO  (qtp522432878-5654) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node12 
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n8&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4016
   [junit4]   2> 1983666 INFO  (qtp522432878-5656) [n:127.0.0.1:41979_solr 
c:readApiTestViolations s:shard1 r:core_node10 
x:readApiTestViolations_shard1_replica_n5] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node10&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4016
   [junit4]   2> 1983793 INFO  
(zkCallback-1274-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1983801 INFO  
(zkCallback-1276-thread-2-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1983809 INFO  
(zkCallback-1276-thread-3-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1984334 INFO  (qtp505155037-5646) [n:127.0.0.1:33767_solr 
c:readApiTestViolations s:shard1 r:core_node9 
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.HttpSolrCall [admin] 
webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n4&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=4741
   [junit4]   2> 1984336 INFO  (qtp505155037-5641) [n:127.0.0.1:33767_solr    ] 
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 1984480 INFO  
(zkCallback-1276-thread-3-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1984480 INFO  
(zkCallback-1274-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1984480 INFO  
(zkCallback-1276-thread-1-processing-n:127.0.0.1:41979_solr) 
[n:127.0.0.1:41979_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/readApiTestViolations/state.json] for collection 
[readApiTestViolations] has occurred - updating... (live nodes size: [2])
   [junit4]   2> 1984558 INFO  
(OverseerCollectionConfigSetProcessor-99164439121428486-127.0.0.1:41979_solr-n_0000000000)
 [n:127.0.0.1:41979_solr    ] 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> 1985338 INFO  (qtp505155037-5641) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=6&collection.configName=conf&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=7067
   [junit4]   2> 1985415 INFO  
(zkConnectionManagerCallback-1315-thread-1-processing-n:127.0.0.1:33767_solr) 
[n:127.0.0.1:33767_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1985419 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1985420 INFO  (qtp505155037-5644) [n:127.0.0.1:33767_solr    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42583/solr ready
   [junit4]   2> 1985550 INFO  (qtp505155037-5752) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core.readApiTestViolations.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.readApiTestViolations.shard1.replica_n4:INDEX.sizeInBytes&key=solr.core.readApiTestViolations.shard1.replica_n7:INDEX.sizeInBytes}
 status=0 QTime=110
   [junit4]   2> 1985586 INFO  (qtp505155037-5753) [n:127.0.0.1:33767_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/me

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

0.0.1:36084_solr c:localShardsTestColl s:shard3 r:core_node15 
x:localShardsTestColl_shard3_replica_n12] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.localShardsTestColl.shard3.leader, tag=530706457
   [junit4]   2> 372041 INFO  (coreCloseExecutor-618-thread-8) 
[n:127.0.0.1:36084_solr c:overwrite s:shard1 r:core_node2 
x:overwrite_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.overwrite.shard1.leader, tag=1866811824
   [junit4]   2> 372042 INFO  (coreCloseExecutor-617-thread-7) 
[n:127.0.0.1:37278_solr c:2nd_collection s:shard1 r:core_node3 
x:2nd_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.2nd_collection.shard1.leader, 
tag=350302663
   [junit4]   2> 372048 INFO  (coreCloseExecutor-617-thread-8) 
[n:127.0.0.1:37278_solr c:stale_state_test_col s:shard1 r:core_node4 
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing 
metric reporters for registry=solr.core.stale_state_test_col.shard1.replica_n3, 
tag=1325156798
   [junit4]   2> 372048 INFO  (coreCloseExecutor-617-thread-8) 
[n:127.0.0.1:37278_solr c:stale_state_test_col s:shard1 r:core_node4 
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter Closing 
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c951352: rootName 
= solr_37278, domain = solr.core.stale_state_test_col.shard1.replica_n3, 
service url = null, agent id = null] for registry 
solr.core.stale_state_test_col.shard1.replica_n3 / 
com.codahale.metrics.MetricRegistry@179f6576
   [junit4]   2> 372210 INFO  (coreCloseExecutor-617-thread-8) 
[n:127.0.0.1:37278_solr c:stale_state_test_col s:shard1 r:core_node4 
x:stale_state_test_col_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.stale_state_test_col.shard1.leader, tag=1325156798
   [junit4]   2> 372223 INFO  (coreCloseExecutor-617-thread-1) 
[n:127.0.0.1:37278_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric 
reporters for registry=solr.collection.collection1.shard1.leader, tag=34324404
   [junit4]   2> 372223 INFO  (coreCloseExecutor-617-thread-1) 
[n:127.0.0.1:37278_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on 
IndexWriter close.
   [junit4]   2> 372223 INFO  (coreCloseExecutor-617-thread-1) 
[n:127.0.0.1:37278_solr c:collection1 s:shard1 r:core_node3 
x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData 
with IW:org.apache.solr.update.SolrIndexWriter@4fc4533 commitCommandVersion:0
   [junit4]   2> 372243 INFO  (coreCloseExecutor-617-thread-3) 
[n:127.0.0.1:37278_solr c:localShardsTestColl s:shard1 r:core_node4 
x:localShardsTestColl_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.localShardsTestColl.shard1.leader, tag=1743821823
   [junit4]   2> 372243 INFO  (coreCloseExecutor-617-thread-5) 
[n:127.0.0.1:37278_solr c:localShardsTestColl s:shard3 r:core_node17 
x:localShardsTestColl_shard3_replica_n14] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.localShardsTestColl.shard3.leader, tag=961007393
   [junit4]   2> 372734 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:36084/solr/localShardsTestColl_shard2_replica_n6/
   [junit4]   2> 372735 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.c.SyncStrategy Sync Success - 
now sync replicas to me
   [junit4]   2> 372735 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.c.SyncStrategy 
https://127.0.0.1:36084/solr/localShardsTestColl_shard2_replica_n6/ has no 
replicas
   [junit4]   2> 372735 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.c.SolrCore 
[localShardsTestColl_shard2_replica_n6]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4ada96df
   [junit4]   2> 372735 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.m.SolrMetricManager Closing 
metric reporters for registry=solr.core.localShardsTestColl.shard2.replica_n6, 
tag=1255839455
   [junit4]   2> 372735 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter Closing 
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3bc19506: rootName 
= solr_36084, domain = solr.core.localShardsTestColl.shard2.replica_n6, service 
url = null, agent id = null] for registry 
solr.core.localShardsTestColl.shard2.replica_n6 / 
com.codahale.metrics.MetricRegistry@7b8f746e
   [junit4]   2> 372752 INFO  (jetty-closer-132-thread-1) [    ] 
o.a.s.c.Overseer Overseer 
(id=99164814608760840-127.0.0.1:36084_solr-n_0000000000) closing
   [junit4]   2> 372752 INFO  
(OverseerStateUpdate-99164814608760840-127.0.0.1:36084_solr-n_0000000000) 
[n:127.0.0.1:36084_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:36084_solr
   [junit4]   2> 372808 WARN  
(OverseerAutoScalingTriggerThread-99164814608760840-127.0.0.1:36084_solr-n_0000000000)
 [n:127.0.0.1:36084_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 372922 INFO  
(zkCallback-155-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr c:localShardsTestColl s:shard2 r:core_node9 
x:localShardsTestColl_shard2_replica_n6] o.a.s.m.SolrMetricManager Closing 
metric reporters for 
registry=solr.collection.localShardsTestColl.shard2.leader, tag=1255839455
   [junit4]   2> 373101 WARN  
(zkConnectionManagerCallback-156-thread-1-processing-n:127.0.0.1:36084_solr) 
[n:127.0.0.1:36084_solr    ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@49fbcba5 name: 
ZooKeeperConnection Watcher:127.0.0.1:35305/solr got event WatchedEvent 
state:Disconnected type:None path:null path: null type: None
   [junit4]   2> 373139 INFO  (jetty-closer-132-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1fb36aaf{/solr,null,UNAVAILABLE}
   [junit4]   2> 374560 INFO  (jetty-closer-132-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@32ca94dd{/solr,null,UNAVAILABLE}
   [junit4]   2> 374561 ERROR 
(SUITE-CloudSolrClientTest-seed#[BA8883C80D40EB73]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 374638 INFO  
(SUITE-CloudSolrClientTest-seed#[BA8883C80D40EB73]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35305 35305
   [junit4]   2> 374911 INFO  (Thread-113) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:35305 35305
   [junit4]   2> 374932 WARN  (Thread-113) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        3       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        40      /solr/collections/localShardsTestColl/state.json
   [junit4]   2>        26      /solr/collections/foo/state.json
   [junit4]   2>        18      /solr/collections/multicollection1/state.json
   [junit4]   2>        18      /solr/collections/multicollection2/state.json
   [junit4]   2>        16      
/solr/collections/stale_state_test_col/state.json
   [junit4]   2>        15      /solr/collections/collection1/state.json
   [junit4]   2>        14      /solr/collections/2nd_collection/state.json
   [junit4]   2>        12      /solr/collections/nemesis/state.json
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        6       /solr/collections/overwrite/state.json
   [junit4]   2>        2       
/solr/overseer_elect/election/99164814608760840-127.0.0.1:36084_solr-n_0000000000
   [junit4]   2>        2       
/solr/collections/localShardsTestColl/leader_elect/shard3/election/99164814608760840-core_node15-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/live_nodes
   [junit4]   2>        9       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_BA8883C80D40EB73-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6b6d74c0),
 locale=mt, timezone=America/Rosario
   [junit4]   2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=173338768,total=473432064
   [junit4]   2> NOTE: All tests run in this JVM: [TestCoreAdmin, 
LargeVolumeJettyTest, SolrExceptionTest, TestJsonRecordReader, 
RegressionEvaluatorTest, OperationsTest, SolrExampleXMLTest, 
CosineEvaluatorTest, CloudSolrClientBuilderTest, ArcCosineEvaluatorTest, 
TestCollectionStateWatchers, TestSuggesterResponse, TestCollectionAdminRequest, 
AddEvaluatorTest, HyperbolicSineEvaluatorTest, TestJavaBinCodec, 
CloudSolrClientTest]
   [junit4] Completed [63/146 (1!)] on J2 in 116.05s, 16 tests, 1 error <<< 
FAILURES!

[...truncated 46625 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to