lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_131) - Build # 6664 - Still Unstable!
Date Tue, 20 Jun 2017 07:39:29 GMT
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6664/
Java: 64bit/jdk1.8.0_131 -XX:+UseCompressedOops -XX:+UseG1GC

1 tests failed.
FAILED:  org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences

Error Message:
Adding a policy with 'cores' attribute should not have succeeded.

Stack Trace:
java.lang.AssertionError: Adding a policy with 'cores' attribute should not have succeeded.
	at __randomizedtesting.SeedInfo.seed([2D99E9BDEEFB8943:8C51B79497BD8F4D]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at 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)




Build Log:
[...truncated 11794 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\init-core-data-001
   [junit4]   2> 1266533 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 1266534 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 1266535 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001
   [junit4]   2> 1266535 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1266536 INFO  (Thread-1878) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1266536 INFO  (Thread-1878) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1266539 ERROR (Thread-1878) [    ] 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> 1266635 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:50707
   [junit4]   2> 1266645 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 0x15cc4429eaa0000, 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> 1266647 INFO  (jetty-launcher-1313-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1266647 INFO  (jetty-launcher-1313-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1266648 INFO  (jetty-launcher-1313-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28a1dbe3{/solr,null,AVAILABLE}
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@326ff439{HTTP/1.1,[http/1.1]}{127.0.0.1:50711}
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.e.j.s.Server Started @1270611ms
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50711}
   [junit4]   2> 1266649 ERROR (jetty-launcher-1313-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1266649 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-20T06:48:39.449Z
   [junit4]   2> 1266650 INFO  (jetty-launcher-1313-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5a419f3d{/solr,null,AVAILABLE}
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@245567ea{HTTP/1.1,[http/1.1]}{127.0.0.1:50716}
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.e.j.s.Server Started @1270613ms
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50716}
   [junit4]   2> 1266651 ERROR (jetty-launcher-1313-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1266651 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-06-20T06:48:39.451Z
   [junit4]   2> 1266659 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1266659 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1266670 INFO  (jetty-launcher-1313-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50707/solr
   [junit4]   2> 1266670 INFO  (jetty-launcher-1313-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50707/solr
   [junit4]   2> 1266674 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 0x15cc4429eaa0004, 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> 1266708 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1266708 INFO  (jetty-launcher-1313-thread-1) [n:127.0.0.1:50711_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 1266714 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50716_solr
   [junit4]   2> 1266715 INFO  (jetty-launcher-1313-thread-1) [n:127.0.0.1:50711_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50711_solr
   [junit4]   2> 1266717 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.c.Overseer Overseer (id=98169082304135174-127.0.0.1:50716_solr-n_0000000000) starting
   [junit4]   2> 1266719 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1266721 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1266727 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50716_solr
   [junit4]   2> 1266733 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1266733 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1266863 INFO  (jetty-launcher-1313-thread-1) [n:127.0.0.1:50711_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50711.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1266865 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50716.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1266867 INFO  (jetty-launcher-1313-thread-1) [n:127.0.0.1:50711_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50711.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1266867 INFO  (jetty-launcher-1313-thread-1) [n:127.0.0.1:50711_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50711.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1266869 INFO  (jetty-launcher-1313-thread-1) [n:127.0.0.1:50711_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\.
   [junit4]   2> 1266870 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50716.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1266871 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50716.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1266872 INFO  (jetty-launcher-1313-thread-2) [n:127.0.0.1:50716_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\.
   [junit4]   2> 1266915 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 0x15cc4429eaa0007, 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> 1266924 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1266925 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50707/solr ready
   [junit4]   2> 1266944 INFO  (TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[2D99E9BDEEFB8943]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
   [junit4]   2> 1267019 ERROR (qtp484920682-10476) [n:127.0.0.1:50711_solr    ] o.a.s.h.RequestHandlerBase java.lang.RuntimeException: cores is only allowed in 'cluster-policy'
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.Policy.lambda$null$1(Policy.java:107)
   [junit4]   2> 	at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:174)
   [junit4]   2> 	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
   [junit4]   2> 	at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1374)
   [junit4]   2> 	at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
   [junit4]   2> 	at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
   [junit4]   2> 	at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
   [junit4]   2> 	at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
   [junit4]   2> 	at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.Policy.lambda$new$2(Policy.java:112)
   [junit4]   2> 	at java.util.HashMap.forEach(HashMap.java:1288)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.Policy.<init>(Policy.java:102)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:275)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.zkSetPolicies(AutoScalingHandler.java:224)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleSetPolicies(AutoScalingHandler.java:205)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:104)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
   [junit4]   2> 	at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2> 	at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:312)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1267021 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=500 QTime=62
   [junit4]   2> 1267021 ERROR (qtp484920682-10476) [n:127.0.0.1:50711_solr    ] o.a.s.s.HttpSolrCall null:java.lang.RuntimeException: cores is only allowed in 'cluster-policy'
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.Policy.lambda$null$1(Policy.java:107)
   [junit4]   2> 	at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:174)
   [junit4]   2> 	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193)
   [junit4]   2> 	at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1374)
   [junit4]   2> 	at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481)
   [junit4]   2> 	at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
   [junit4]   2> 	at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
   [junit4]   2> 	at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
   [junit4]   2> 	at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.Policy.lambda$new$2(Policy.java:112)
   [junit4]   2> 	at java.util.HashMap.forEach(HashMap.java:1288)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.Policy.<init>(Policy.java:102)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:275)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.zkSetPolicies(AutoScalingHandler.java:224)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleSetPolicies(AutoScalingHandler.java:205)
   [junit4]   2> 	at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:104)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
   [junit4]   2> 	at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
   [junit4]   2> 	at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:312)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1267023 INFO  (TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[2D99E9BDEEFB8943]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoScalingHandlerTest -Dtests.method=testPolicyAndPreferences -Dtests.seed=2D99E9BDEEFB8943 -Dtests.slow=true -Dtests.locale=sl-SI -Dtests.timezone=Africa/Libreville -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 0.10s J0 | AutoScalingHandlerTest.testPolicyAndPreferences <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Adding a policy with 'cores' attribute should not have succeeded.
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([2D99E9BDEEFB8943:8C51B79497BD8F4D]:0)
   [junit4]    > 	at org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 1267041 INFO  (TEST-AutoScalingHandlerTest.testReadApi-seed#[2D99E9BDEEFB8943]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testReadApi
   [junit4]   2> 1267058 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1267059 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50707/solr ready
   [junit4]   2> 1267085 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_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> 1267087 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_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> 1267092 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=47
   [junit4]   2> 1267103 INFO  (qtp484920682-10461) [n:127.0.0.1:50711_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1267105 INFO  (qtp484920682-10461) [n:127.0.0.1:50711_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50707/solr ready
   [junit4]   2> 1267110 INFO  (qtp2069699438-10465) [n:127.0.0.1:50716_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=3
   [junit4]   2> 1267115 INFO  (qtp484920682-10459) [n:127.0.0.1:50711_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=3
   [junit4]   2> 1267116 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 0x15cc4429eaa000a, 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> 1267117 INFO  (qtp484920682-10461) [n:127.0.0.1:50711_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=24
   [junit4]   2> 1267124 INFO  (qtp484920682-10458) [n:127.0.0.1:50711_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1267125 INFO  (qtp484920682-10458) [n:127.0.0.1:50711_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50707/solr ready
   [junit4]   2> 1267129 INFO  (qtp2069699438-10480) [n:127.0.0.1:50716_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=2
   [junit4]   2> 1267132 INFO  (qtp484920682-10478) [n:127.0.0.1:50711_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=2
   [junit4]   2> 1267133 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 0x15cc4429eaa000b, 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> 1267134 INFO  (qtp484920682-10458) [n:127.0.0.1:50711_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=15
   [junit4]   2> 1267135 INFO  (qtp2069699438-10479) [n:127.0.0.1:50716_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1267140 INFO  (qtp2069699438-10481) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1267141 INFO  (qtp2069699438-10481) [n:127.0.0.1:50716_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50707/solr ready
   [junit4]   2> 1267145 INFO  (qtp2069699438-10466) [n:127.0.0.1:50716_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=2
   [junit4]   2> 1267148 INFO  (qtp484920682-10456) [n:127.0.0.1:50711_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=2
   [junit4]   2> 1267150 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 0x15cc4429eaa000c, 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> 1267150 INFO  (qtp2069699438-10481) [n:127.0.0.1:50716_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics params={wt=javabin&version=2} status=0 QTime=14
   [junit4]   2> 1267151 INFO  (qtp2069699438-10469) [n:127.0.0.1:50716_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=6&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 1267155 INFO  (OverseerThreadFactory-3932-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_solr    ] o.a.s.c.CreateCollectionCmd Create collection readApiTestViolations
   [junit4]   2> 1267155 INFO  (OverseerThreadFactory-3932-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_solr    ] o.a.s.c.CreateCollectionCmd Only one config set found in zk - using it:conf
   [junit4]   2> 1267156 WARN  (OverseerThreadFactory-3932-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_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> 1267265 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&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> 1267266 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&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> 1267266 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1267268 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&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> 1267269 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n2&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1267269 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 1267268 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&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> 1267271 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n6&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1267387 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_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> 1267387 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_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> 1268313 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1268314 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1268315 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1268313 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1268321 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1268329 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n3] Schema name=minimal
   [junit4]   2> 1268329 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n6] Schema name=minimal
   [junit4]   2> 1268329 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1268330 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n4] Schema name=minimal
   [junit4]   2> 1268351 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1268351 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  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> 1268351 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1268351 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1268352 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n6' using configuration from collection readApiTestViolations, trusted=true
   [junit4]   2> 1268352 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50711.solr.core.readApiTestViolations.shard1.replica_n3' (registry 'solr.core.readApiTestViolations.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1268352 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1268352 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n3] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\readApiTestViolations_shard1_replica_n3], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\.\readApiTestViolations_shard1_replica_n3\data\]
   [junit4]   2> 1268353 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1268353 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  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> 1268353 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50716.solr.core.readApiTestViolations.shard1.replica_n4' (registry 'solr.core.readApiTestViolations.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1268354 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1268354 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\readApiTestViolations_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\.\readApiTestViolations_shard1_replica_n4\data\]
   [junit4]   2> 1268354 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 1268351 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  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> 1268352 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50716.solr.core.readApiTestViolations.shard1.replica_n6' (registry 'solr.core.readApiTestViolations.shard1.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1268356 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50711.solr.core.readApiTestViolations.shard1.replica_n1' (registry 'solr.core.readApiTestViolations.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1268356 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1268356 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1268356 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n6] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\readApiTestViolations_shard1_replica_n6], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\.\readApiTestViolations_shard1_replica_n6\data\]
   [junit4]   2> 1268356 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\readApiTestViolations_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\.\readApiTestViolations_shard1_replica_n1\data\]
   [junit4]   2> 1268358 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1268358 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'readApiTestViolations_shard1_replica_n2' using configuration from collection readApiTestViolations, trusted=true
   [junit4]   2> 1268359 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50716.solr.core.readApiTestViolations.shard1.replica_n2' (registry 'solr.core.readApiTestViolations.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1268359 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1268359 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\readApiTestViolations_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node1\.\readApiTestViolations_shard1_replica_n2\data\]
   [junit4]   2> 1268364 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1268371 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n5] Schema name=minimal
   [junit4]   2> 1268374 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1268374 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  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> 1268375 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50711.solr.core.readApiTestViolations.shard1.replica_n5' (registry 'solr.core.readApiTestViolations.shard1.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@d442d2d
   [junit4]   2> 1268375 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1268375 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n5] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\readApiTestViolations_shard1_replica_n5], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001\tempDir-001\node2\.\readApiTestViolations_shard1_replica_n5\data\]
   [junit4]   2> 1268499 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1268499 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1268503 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1268503 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1268523 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1268523 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1268529 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1268529 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1268531 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1268531 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1268534 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1268534 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1268537 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1268539 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1268546 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1268546 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1268551 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@94ca9ed[readApiTestViolations_shard1_replica_n3] main]
   [junit4]   2> 1268551 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d8ebb6b[readApiTestViolations_shard1_replica_n1] main]
   [junit4]   2> 1268551 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1268551 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1268551 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1268555 INFO  (searcherExecutor-3940-thread-1-processing-n:127.0.0.1:50711_solr x:readApiTestViolations_shard1_replica_n1 s:shard1 c:readApiTestViolations) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n1] Registered new searcher Searcher@4d8ebb6b[readApiTestViolations_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1268556 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570705318982189056
   [junit4]   2> 1268558 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1268558 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1268559 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1268560 INFO  (searcherExecutor-3937-thread-1-processing-n:127.0.0.1:50711_solr x:readApiTestViolations_shard1_replica_n3 s:shard1 c:readApiTestViolations) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n3] Registered new searcher Searcher@94ca9ed[readApiTestViolations_shard1_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1268561 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570705318987431936
   [junit4]   2> 1268563 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@67d591dc[readApiTestViolations_shard1_replica_n4] main]
   [junit4]   2> 1268565 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@31ee8780[readApiTestViolations_shard1_replica_n6] main]
   [junit4]   2> 1268565 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1268566 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1268566 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1268566 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=6 found=1 timeoutin=9999ms
   [junit4]   2> 1268566 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1268568 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1268568 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570705318994771968
   [junit4]   2> 1268568 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1268568 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570705318994771968
   [junit4]   2> 1268568 INFO  (searcherExecutor-3939-thread-1-processing-n:127.0.0.1:50716_solr x:readApiTestViolations_shard1_replica_n4 s:shard1 c:readApiTestViolations) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n4] Registered new searcher Searcher@67d591dc[readApiTestViolations_shard1_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1268569 INFO  (searcherExecutor-3938-thread-1-processing-n:127.0.0.1:50716_solr x:readApiTestViolations_shard1_replica_n6 s:shard1 c:readApiTestViolations) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n6] Registered new searcher Searcher@31ee8780[readApiTestViolations_shard1_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1268574 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1268574 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1268577 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1268578 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1268581 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@ba8a45f[readApiTestViolations_shard1_replica_n2] main]
   [junit4]   2> 1268584 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1268584 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1268585 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1268585 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570705319012597760
   [junit4]   2> 1268585 INFO  (searcherExecutor-3941-thread-1-processing-n:127.0.0.1:50716_solr x:readApiTestViolations_shard1_replica_n2 s:shard1 c:readApiTestViolations) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n2] Registered new searcher Searcher@ba8a45f[readApiTestViolations_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1268631 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1268631 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1268636 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1268636 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1268640 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b738a5f[readApiTestViolations_shard1_replica_n5] main]
   [junit4]   2> 1268641 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 1268642 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1268643 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1268643 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1570705319073415168
   [junit4]   2> 1268646 INFO  (searcherExecutor-3942-thread-1-processing-n:127.0.0.1:50711_solr x:readApiTestViolations_shard1_replica_n5 s:shard1 c:readApiTestViolations) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n5] Registered new searcher Searcher@2b738a5f[readApiTestViolations_shard1_replica_n5] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1268672 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_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> 1268672 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_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> 1269077 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1269077 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1269077 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50711/solr/readApiTestViolations_shard1_replica_n3/
   [junit4]   2> 1269098 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=readApiTestViolations_shard1_replica_n3 url=http://127.0.0.1:50711/solr START replicas=[http://127.0.0.1:50711/solr/readApiTestViolations_shard1_replica_n1/, http://127.0.0.1:50716/solr/readApiTestViolations_shard1_replica_n4/, http://127.0.0.1:50716/solr/readApiTestViolations_shard1_replica_n2/, http://127.0.0.1:50711/solr/readApiTestViolations_shard1_replica_n5/, http://127.0.0.1:50716/solr/readApiTestViolations_shard1_replica_n6/] nUpdates=100
   [junit4]   2> 1269098 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.u.PeerSync PeerSync: core=readApiTestViolations_shard1_replica_n3 url=http://127.0.0.1:50711/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 1269108 INFO  (qtp2069699438-10465) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node3 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=7
   [junit4]   2> 1269111 INFO  (qtp484920682-10460) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.S.Request [readApiTestViolations_shard1_replica_n1]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 1269113 INFO  (qtp484920682-10460) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node5 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=1
   [junit4]   2> 1269113 INFO  (qtp2069699438-10479) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n2] o.a.s.c.S.Request [readApiTestViolations_shard1_replica_n2]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 1269114 INFO  (qtp2069699438-10479) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n6] o.a.s.c.S.Request [readApiTestViolations_shard1_replica_n6]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1269114 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 1269114 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] 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> 1269114 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 1269120 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50711/solr/readApiTestViolations_shard1_replica_n3/ shard1
   [junit4]   2> 1269231 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_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> 1269231 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_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> 1269273 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1269277 INFO  (qtp484920682-10476) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&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=2012
   [junit4]   2> 1269377 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_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> 1269377 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_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> 1269576 INFO  (qtp2069699438-10467) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n6&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2305
   [junit4]   2> 1269577 INFO  (qtp2069699438-10468) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&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=2309
   [junit4]   2> 1269580 INFO  (qtp484920682-10463) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&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=2314
   [junit4]   2> 1269593 INFO  (qtp2069699438-10470) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n2&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2325
   [junit4]   2> 1269654 INFO  (qtp484920682-10462) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1  x:readApiTestViolations_shard1_replica_n5] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&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=2386
   [junit4]   2> 1269657 INFO  (qtp2069699438-10469) [n:127.0.0.1:50716_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> 1269755 INFO  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_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> 1269755 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_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> 1270659 INFO  (qtp2069699438-10469) [n:127.0.0.1:50716_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=6&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=3506
   [junit4]   2> 1270664 INFO  (qtp2069699438-10466) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1270665 INFO  (qtp2069699438-10466) [n:127.0.0.1:50716_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50707/solr ready
   [junit4]   2> 1270676 INFO  (qtp2069699438-10481) [n:127.0.0.1:50716_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=2
   [junit4]   2> 1270682 INFO  (qtp484920682-10459) [n:127.0.0.1:50711_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=4
   [junit4]   2> 1270689 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 0x15cc4429eaa000d, 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> 1270689 INFO  (qtp2069699438-10466) [n:127.0.0.1:50716_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics params={wt=javabin&version=2} status=0 QTime=30
   [junit4]   2> 1270690 INFO  (TEST-AutoScalingHandlerTest.testReadApi-seed#[2D99E9BDEEFB8943]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testReadApi
   [junit4]   2> 1270691 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 0x15cc4429eaa0008, 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> 1270694 INFO  (jetty-closer-1314-thread-1) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@326ff439{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1270695 INFO  (jetty-closer-1314-thread-1) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=474480501
   [junit4]   2> 1270695 INFO  (jetty-closer-1314-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1270695 INFO  (jetty-closer-1314-thread-2) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@245567ea{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1270696 INFO  (jetty-closer-1314-thread-2) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1488062772
   [junit4]   2> 1270696 INFO  (jetty-closer-1314-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 1270697 INFO  (jetty-closer-1314-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1270698 INFO  (jetty-closer-1314-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 1270698 INFO  (jetty-closer-1314-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1270699 INFO  (jetty-closer-1314-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 1270700 INFO  (jetty-closer-1314-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
   [junit4]   2> 1270701 INFO  (jetty-closer-1314-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
   [junit4]   2> 1270704 INFO  (coreCloseExecutor-3967-thread-3) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node5 x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n5]  CLOSING SolrCore org.apache.solr.core.SolrCore@63fc7651
   [junit4]   2> 1270705 INFO  (coreCloseExecutor-3967-thread-2) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3b63250e
   [junit4]   2> 1270705 INFO  (coreCloseExecutor-3967-thread-1) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node1 x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n3]  CLOSING SolrCore org.apache.solr.core.SolrCore@48a3bc1
   [junit4]   2> 1270706 INFO  (coreCloseExecutor-3968-thread-3) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n2]  CLOSING SolrCore org.apache.solr.core.SolrCore@25883bfb
   [junit4]   2> 1270706 INFO  (coreCloseExecutor-3968-thread-2) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n6]  CLOSING SolrCore org.apache.solr.core.SolrCore@57ebcc77
   [junit4]   2> 1270707 INFO  (coreCloseExecutor-3968-thread-1) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node3 x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n4]  CLOSING SolrCore org.apache.solr.core.SolrCore@664c3da9
   [junit4]   2> 1270708 INFO  (coreCloseExecutor-3967-thread-1) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node1 x:readApiTestViolations_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.readApiTestViolations.shard1.replica_n3, tag=76168129
   [junit4]   2> 1270709 INFO  (coreCloseExecutor-3968-thread-2) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.readApiTestViolations.shard1.replica_n6, tag=1475071095
   [junit4]   2> 1270712 INFO  (coreCloseExecutor-3967-thread-1) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node1 x:readApiTestViolations_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.readApiTestViolations.shard1.leader, tag=76168129
   [junit4]   2> 1270712 INFO  (coreCloseExecutor-3967-thread-2) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.readApiTestViolations.shard1.replica_n1, tag=996353294
   [junit4]   2> 1270713 INFO  (coreCloseExecutor-3968-thread-2) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node6 x:readApiTestViolations_shard1_replica_n6] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.readApiTestViolations.shard1.leader, tag=1475071095
   [junit4]   2> 1270713 INFO  (coreCloseExecutor-3968-thread-3) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.readApiTestViolations.shard1.replica_n2, tag=629685243
   [junit4]   2> 1270717 INFO  (coreCloseExecutor-3967-thread-3) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node5 x:readApiTestViolations_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.readApiTestViolations.shard1.replica_n5, tag=1677489745
   [junit4]   2> 1270718 INFO  (coreCloseExecutor-3968-thread-3) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node4 x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.readApiTestViolations.shard1.leader, tag=629685243
   [junit4]   2> 1270718 INFO  (coreCloseExecutor-3968-thread-1) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node3 x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.readApiTestViolations.shard1.replica_n4, tag=1716272553
   [junit4]   2> 1270720 INFO  (coreCloseExecutor-3967-thread-3) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node5 x:readApiTestViolations_shard1_replica_n5] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.readApiTestViolations.shard1.leader, tag=1677489745
   [junit4]   2> 1270720 INFO  (coreCloseExecutor-3967-thread-2) [n:127.0.0.1:50711_solr c:readApiTestViolations s:shard1 r:core_node2 x:readApiTestViolations_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.readApiTestViolations.shard1.leader, tag=996353294
   [junit4]   2> 1270722 INFO  (coreCloseExecutor-3968-thread-1) [n:127.0.0.1:50716_solr c:readApiTestViolations s:shard1 r:core_node3 x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.readApiTestViolations.shard1.leader, tag=1716272553
   [junit4]   2> 1270724 INFO  (jetty-closer-1314-thread-2) [    ] o.a.s.c.Overseer Overseer (id=98169082304135174-127.0.0.1:50716_solr-n_0000000000) closing
   [junit4]   2> 1270725 INFO  (OverseerStateUpdate-98169082304135174-127.0.0.1:50716_solr-n_0000000000) [n:127.0.0.1:50716_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50716_solr
   [junit4]   2> 1270725 WARN  (zkCallback-1324-thread-1-processing-n:127.0.0.1:50711_solr) [n:127.0.0.1:50711_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1270726 INFO  (zkCallback-1325-thread-1-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 1270726 INFO  (jetty-closer-1314-thread-1) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@28a1dbe3{/solr,null,UNAVAILABLE}
   [junit4]   2> 1270728 WARN  (zkCallback-1325-thread-4-processing-n:127.0.0.1:50716_solr) [n:127.0.0.1:50716_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1270728 INFO  (jetty-closer-1314-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5a419f3d{/solr,null,UNAVAILABLE}
   [junit4]   2> 1270729 ERROR (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-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> 1270730 INFO  (SUITE-AutoScalingHandlerTest-seed#[2D99E9BDEEFB8943]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50707 50707
   [junit4]   2> 1270732 INFO  (Thread-1878) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50707 50707
   [junit4]   2> 1270733 WARN  (Thread-1878) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/aliases.json
   [junit4]   2> 	8	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/security.json
   [junit4]   2> 	2	/solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/clusterstate.json
   [junit4]   2> 	2	/solr/collections/readApiTestViolations/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	8	/solr/live_nodes
   [junit4]   2> 	8	/solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.autoscaling.AutoScalingHandlerTest_2D99E9BDEEFB8943-001
   [junit4]   2> Jun 20, 2017 6:48:43 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 5 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=422, maxMBSortInHeap=7.87467254435224, sim=RandomSimilarity(queryNorm=false): {}, locale=sl-SI, timezone=Africa/Libreville
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=255271728,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestUseDocValuesAsStored, TestClassNameShortening, TestIBSimilarityFactory, DistribJoinFromCollectionTest, RestartWhileUpdatingTest, TestBlobHandler, ParsingFieldUpdateProcessorsTest, TestPullReplica, TestTolerantUpdateProcessorRandomCloud, TestDFISimilarityFactory, HighlighterTest, LeaderFailureAfterFreshStartTest, DistributedFacetExistsSmallTest, FileUtilsTest, ImplicitSnitchTest, CdcrReplicationHandlerTest, SpatialFilterTest, OutputWriterTest, ZkNodePropsTest, TestSolrFieldCacheBean, TestMultiValuedNumericRangeQuery, DirectoryFactoryTest, CollectionTooManyReplicasTest, CdcrVersionReplicationTest, TestMergePolicyConfig, TestLegacyNumericUtils, TestRuleBasedAuthorizationPlugin, TestPerFieldSimilarity, TestRTGBase, PreAnalyzedFieldManagedSchemaCloudTest, AddBlockUpdateTest, SolrShardReporterTest, CursorPagingTest, DistributedQueryComponentCustomSortTest, TestPushWriter, HdfsUnloadDistributedZkTest, TestTolerantSearch, DeleteNodeTest, DistributedDebugComponentTest, TestSolrDeletionPolicy1, SolrTestCaseJ4Test, TestRTimerTree, TestCollationField, TestImplicitCoreProperties, TestFunctionQuery, TestCloudJSONFacetJoinDomain, UnloadDistributedZkTest, SchemaVersionSpecificBehaviorTest, TestSurroundQueryParser, TestLegacyField, DocExpirationUpdateProcessorFactoryTest, SolrCLIZkUtilsTest, DistributedMLTComponentTest, MissingSegmentRecoveryTest, DirectSolrConnectionTest, TestPerFieldSimilarityWithDefaultOverride, AsyncCallRequestStatusResponseTest, TestRemoteStreaming, TestDeleteCollectionOnDownNodes, SimplePostToolTest, RulesTest, TestCustomStream, DocumentAnalysisRequestHandlerTest, BigEndianAscendingWordSerializerTest, MoreLikeThisHandlerTest, TestExactSharedStatsCache, TestLeaderElectionZkExpiry, TestMiniSolrCloudClusterSSL, TestReloadAndDeleteDocs, TestTestInjection, BasicDistributedZkTest, DocumentBuilderTest, ClassificationUpdateProcessorTest, TestJmxIntegration, MetricsConfigTest, TestRestManager, TestGraphMLResponseWriter, TestRandomCollapseQParserPlugin, DistribCursorPagingTest, BlockJoinFacetDistribTest, SolrSlf4jReporterTest, TestFieldCollectionResource, TestApiFramework, InfoHandlerTest, DateFieldTest, TestElisionMultitermQuery, SolrCoreTest, TestCursorMarkWithoutUniqueKey, ChaosMonkeySafeLeaderWithPullReplicasTest, PolyFieldTest, TestScoreJoinQPNoScore, SpellCheckCollatorWithCollapseTest, DateRangeFieldTest, TestHdfsUpdateLog, QueryEqualityTest, TestPseudoReturnFields, DistributedFacetPivotSmallTest, TestJsonRequest, ZkControllerTest, HdfsRestartWhileUpdatingTest, HdfsRecoverLeaseTest, DateMathParserTest, RAMDirectoryFactoryTest, TestMultiWordSynonyms, TestFieldCache, SolrGraphiteReporterTest, TestReRankQParserPlugin, ClassificationUpdateProcessorFactoryTest, AutoScalingHandlerTest]
   [junit4] Completed [270/725 (1!)] on J0 in 4.72s, 2 tests, 1 failure <<< FAILURES!

[...truncated 44788 lines...]

Mime
View raw message