Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1886/
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([B7F7EB47CDFAAA31:163FB56EB4BCAC3F]: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 11883 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/init-core-data-001
[junit4] 2> 951312 WARN
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 951313 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 951314 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 951315 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001
[junit4] 2> 951315 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 951316 INFO (Thread-4192) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 951316 INFO (Thread-4192) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 951318 ERROR (Thread-4192) [ ] 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> 951416 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:33231
[junit4] 2> 951426 INFO (jetty-launcher-2013-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 951426 INFO (jetty-launcher-2013-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 951428 INFO (jetty-launcher-2013-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@48469052{/solr,null,AVAILABLE}
[junit4] 2> 951428 INFO (jetty-launcher-2013-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@28b87fef{/solr,null,AVAILABLE}
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@1620eadc{SSL,[ssl,
http/1.1]}{127.0.0.1:56081}
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@3944beec{SSL,[ssl,
http/1.1]}{127.0.0.1:58697}
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-1) [ ]
o.e.j.s.Server Started @954393ms
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-2) [ ]
o.e.j.s.Server Started @954393ms
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=56081}
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58697}
[junit4] 2> 951442 ERROR (jetty-launcher-2013-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 951442 ERROR (jetty-launcher-2013-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 951442 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 951443 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951443 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 951443 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 951443 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 951443 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-21T00:23:02.386Z
[junit4] 2> 951443 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-21T00:23:02.386Z
[junit4] 2> 951446 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 951446 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 951456 INFO (jetty-launcher-2013-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33231/solr
[junit4] 2> 951456 INFO (jetty-launcher-2013-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33231/solr
[junit4] 2> 951488 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 951489 INFO (jetty-launcher-2013-thread-2)
[n:127.0.0.1:58697_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 951489 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:56081_solr
[junit4] 2> 951490 INFO (jetty-launcher-2013-thread-2)
[n:127.0.0.1:58697_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58697_solr
[junit4] 2> 951491 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.c.Overseer Overseer
(id=98173228302991365-127.0.0.1:56081_solr-n_0000000000) starting
[junit4] 2> 951499 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 951499 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 951512 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56081_solr
[junit4] 2> 951513 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 951513 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 951567 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56081.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 951572 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56081.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 951572 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56081.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 951574 INFO (jetty-launcher-2013-thread-1)
[n:127.0.0.1:56081_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/.
[junit4] 2> 951598 INFO (jetty-launcher-2013-thread-2)
[n:127.0.0.1:58697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58697.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 951603 INFO (jetty-launcher-2013-thread-2)
[n:127.0.0.1:58697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58697.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 951603 INFO (jetty-launcher-2013-thread-2)
[n:127.0.0.1:58697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58697.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 951604 INFO (jetty-launcher-2013-thread-2)
[n:127.0.0.1:58697_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/.
[junit4] 2> 951637 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 951638 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33231/solr ready
[junit4] 2> 951660 INFO
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[B7F7EB47CDFAAA31])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
[junit4] 2> 951712 ERROR (qtp343564384-12151) [n:127.0.0.1:58697_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.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 951712 INFO (qtp343564384-12151) [n:127.0.0.1:58697_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=500 QTime=28
[junit4] 2> 951712 ERROR (qtp343564384-12151) [n:127.0.0.1:58697_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.ssl.SslConnection.onFillable(SslConnection.java:202)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 951713 INFO
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[B7F7EB47CDFAAA31])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=AutoScalingHandlerTest -Dtests.method=testPolicyAndPreferences
-Dtests.seed=B7F7EB47CDFAAA31 -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=it-CH -Dtests.timezone=AGT -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.07s J1 | AutoScalingHandlerTest.testPolicyAndPreferences
<<<
[junit4] > Throwable #1: java.lang.AssertionError: Adding a policy with
'cores' attribute should not have succeeded.
[junit4] > at
__randomizedtesting.SeedInfo.seed([B7F7EB47CDFAAA31:163FB56EB4BCAC3F]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 951733 INFO
(TEST-AutoScalingHandlerTest.testReadApi-seed#[B7F7EB47CDFAAA31]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 951760 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 951761 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33231/solr ready
[junit4] 2> 951775 INFO (qtp1372685006-12145) [n:127.0.0.1:56081_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=1
[junit4] 2> 951779 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 951782 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=27
[junit4] 2> 951786 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 951787 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33231/solr ready
[junit4] 2> 951796 INFO (qtp1372685006-12148) [n:127.0.0.1:56081_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=8
[junit4] 2> 951798 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 951803 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=20
[junit4] 2> 951808 INFO (qtp1372685006-12141) [n:127.0.0.1:56081_solr
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 951809 INFO (qtp1372685006-12141) [n:127.0.0.1:56081_solr
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33231/solr ready
[junit4] 2> 951811 INFO (qtp1372685006-12139) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=1
[junit4] 2> 951815 INFO (qtp343564384-12151) [n:127.0.0.1:58697_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 951817 INFO (qtp1372685006-12141) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 951818 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 951824 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 951825 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33231/solr ready
[junit4] 2> 951826 INFO (qtp1372685006-12145) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 951828 INFO (qtp343564384-12142) [n:127.0.0.1:58697_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName,os.systemLoadAverage,memory.heap.usage&wt=javabin&version=2&group=solr.jvm,solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 951829 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics
params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 951830 INFO (qtp1372685006-12148) [n:127.0.0.1:56081_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> 951835 INFO
(OverseerThreadFactory-4115-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_solr ] o.a.s.c.CreateCollectionCmd Create collection
readApiTestViolations
[junit4] 2> 951836 INFO
(OverseerThreadFactory-4115-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_solr ] o.a.s.c.CreateCollectionCmd Only one config set
found in zk - using it:conf
[junit4] 2> 951836 WARN
(OverseerThreadFactory-4115-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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> 951955 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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> 951955 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 951956 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 951957 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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> 951957 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 951961 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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> 951964 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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> 951968 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 952072 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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> 952072 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_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> 952975 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 952980 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 952983 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 952989 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n4] Schema
name=minimal
[junit4] 2> 952989 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 952999 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n2] Schema
name=minimal
[junit4] 2> 953001 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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> 953001 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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> 953002 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58697.solr.core.readApiTestViolations.shard1.replica_n4' (registry
'solr.core.readApiTestViolations.shard1.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 953002 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 953002 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n4] ] Opening new
SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n4],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n4/data/]
[junit4] 2> 953003 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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> 953003 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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> 953004 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58697.solr.core.readApiTestViolations.shard1.replica_n2' (registry
'solr.core.readApiTestViolations.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 953004 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 953004 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n2] ] Opening new
SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n2],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n2/data/]
[junit4] 2> 953012 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 953013 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n3] Schema
name=minimal
[junit4] 2> 953032 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953033 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953033 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56081.solr.core.readApiTestViolations.shard1.replica_n3' (registry
'solr.core.readApiTestViolations.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 953033 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 953033 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n3] ] Opening new
SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n3],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n3/data/]
[junit4] 2> 953036 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 953043 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n5] Schema
name=minimal
[junit4] 2> 953046 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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> 953046 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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> 953047 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56081.solr.core.readApiTestViolations.shard1.replica_n5' (registry
'solr.core.readApiTestViolations.shard1.replica_n5') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 953047 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 953047 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n5] ] Opening new
SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n5],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n5/data/]
[junit4] 2> 953052 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n6] Schema
name=minimal
[junit4] 2> 953056 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 953056 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 953057 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_58697.solr.core.readApiTestViolations.shard1.replica_n6' (registry
'solr.core.readApiTestViolations.shard1.replica_n6') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 953057 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 953057 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n6] ] Opening new
SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n6],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n6/data/]
[junit4] 2> 953057 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.s.IndexSchema [readApiTestViolations_shard1_replica_n1] Schema
name=minimal
[junit4] 2> 953071 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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> 953071 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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> 953071 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_56081.solr.core.readApiTestViolations.shard1.replica_n1' (registry
'solr.core.readApiTestViolations.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@46bc122c
[junit4] 2> 953071 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 953071 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.c.SolrCore [[readApiTestViolations_shard1_replica_n1] ] Opening new
SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n1/data/]
[junit4] 2> 953191 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953191 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953192 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 953192 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 953196 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@106fbb9c[readApiTestViolations_shard1_replica_n3] main]
[junit4] 2> 953198 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 953198 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953199 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 953203 INFO
(searcherExecutor-4122-thread-1-processing-n:127.0.0.1:56081_solr
x:readApiTestViolations_shard1_replica_n3 s:shard1 c:readApiTestViolations)
[n:127.0.0.1:56081_solr c:readApiTestViolations s:shard1
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n3] Registered new searcher
Searcher@106fbb9c[readApiTestViolations_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 953204 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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 1570771654826524672
[junit4] 2> 953212 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953219 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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> 953219 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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> 953221 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 953221 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 953232 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3bf1d241[readApiTestViolations_shard1_replica_n2] main]
[junit4] 2> 953234 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 953234 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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> 953237 INFO (qtp343564384-12149) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 953237 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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 1570771654861127680
[junit4] 2> 953243 INFO
(searcherExecutor-4121-thread-1-processing-n:127.0.0.1:58697_solr
x:readApiTestViolations_shard1_replica_n2 s:shard1 c:readApiTestViolations)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n2] Registered new searcher
Searcher@3bf1d241[readApiTestViolations_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 953249 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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> 953249 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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> 953251 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 953251 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 953253 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@348685e7[readApiTestViolations_shard1_replica_n4] main]
[junit4] 2> 953255 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 953255 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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> 953256 INFO (qtp343564384-12140) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 953257 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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> 953257 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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> 953258 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 953258 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 953260 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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 1570771654885244928
[junit4] 2> 953263 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4919a294[readApiTestViolations_shard1_replica_n1] main]
[junit4] 2> 953264 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 953265 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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> 953266 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 953266 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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 1570771654891536384
[junit4] 2> 953269 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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> 953269 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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> 953273 INFO
(searcherExecutor-4120-thread-1-processing-n:127.0.0.1:58697_solr
x:readApiTestViolations_shard1_replica_n4 s:shard1 c:readApiTestViolations)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n4] Registered new searcher
Searcher@348685e7[readApiTestViolations_shard1_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 953276 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 953276 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 953309 INFO
(searcherExecutor-4125-thread-1-processing-n:127.0.0.1:56081_solr
x:readApiTestViolations_shard1_replica_n1 s:shard1 c:readApiTestViolations)
[n:127.0.0.1:56081_solr c:readApiTestViolations s:shard1
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n1] Registered new searcher
Searcher@4919a294[readApiTestViolations_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 953310 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 953310 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 953311 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7dc417c0[readApiTestViolations_shard1_replica_n5] main]
[junit4] 2> 953311 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 953311 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 953313 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 953313 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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> 953314 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n5]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 953314 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1288cc58[readApiTestViolations_shard1_replica_n6] main]
[junit4] 2> 953314 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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 1570771654941868032
[junit4] 2> 953316 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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> 953319 INFO
(searcherExecutor-4123-thread-1-processing-n:127.0.0.1:56081_solr
x:readApiTestViolations_shard1_replica_n5 s:shard1 c:readApiTestViolations)
[n:127.0.0.1:56081_solr c:readApiTestViolations s:shard1
x:readApiTestViolations_shard1_replica_n5] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n5] Registered new searcher
Searcher@7dc417c0[readApiTestViolations_shard1_replica_n5]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 953321 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_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> 953325 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 953325 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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> 953326 INFO (qtp343564384-12147) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n6]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 953326 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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 1570771654954450944
[junit4] 2> 953341 INFO
(searcherExecutor-4124-thread-1-processing-n:127.0.0.1:58697_solr
x:readApiTestViolations_shard1_replica_n6 s:shard1 c:readApiTestViolations)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1
x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n6] Registered new searcher
Searcher@1288cc58[readApiTestViolations_shard1_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 953712 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 953712 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953712 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:56081/solr/readApiTestViolations_shard1_replica_n3/
[junit4] 2> 953713 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.u.PeerSync PeerSync: core=readApiTestViolations_shard1_replica_n3
url=https://127.0.0.1:56081/solr START
replicas=[https://127.0.0.1:58697/solr/readApiTestViolations_shard1_replica_n2/,
https://127.0.0.1:56081/solr/readApiTestViolations_shard1_replica_n1/,
https://127.0.0.1:58697/solr/readApiTestViolations_shard1_replica_n4/,
https://127.0.0.1:56081/solr/readApiTestViolations_shard1_replica_n5/,
https://127.0.0.1:58697/solr/readApiTestViolations_shard1_replica_n6/]
nUpdates=100
[junit4] 2> 953719 INFO (qtp343564384-12154) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 r:core_node1
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=4
[junit4] 2> 953719 INFO (qtp1372685006-12145) [n:127.0.0.1:56081_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=4
[junit4] 2> 953719 INFO (qtp343564384-12151) [n:127.0.0.1:58697_solr
c:readApiTestViolations s:shard1 r:core_node4
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=1
[junit4] 2> 953731 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.u.PeerSync PeerSync: core=readApiTestViolations_shard1_replica_n3
url=https://127.0.0.1:56081/solr DONE. We have no versions. sync failed.
[junit4] 2> 953731 INFO (qtp1372685006-12139) [n:127.0.0.1:56081_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=10
[junit4] 2> 953736 INFO (qtp343564384-12154) [n:127.0.0.1:58697_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=4
[junit4] 2> 953737 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953737 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953737 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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> 953744 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:56081/solr/readApiTestViolations_shard1_replica_n3/ shard1
[junit4] 2> 953847 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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> 953847 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_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> 953895 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_solr
c:readApiTestViolations s:shard1 x:readApiTestViolations_shard1_replica_n3]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 953902 INFO (qtp1372685006-12146) [n:127.0.0.1:56081_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=1946
[junit4] 2> 954000 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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> 954000 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_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> 954251 INFO (qtp343564384-12149) [n:127.0.0.1:58697_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=2293
[junit4] 2> 954279 INFO (qtp343564384-12140) [n:127.0.0.1:58697_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=2318
[junit4] 2> 954279 INFO (qtp1372685006-12150) [n:127.0.0.1:56081_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=2324
[junit4] 2> 954348 INFO (qtp1372685006-12152) [n:127.0.0.1:56081_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=2383
[junit4] 2> 954350 INFO (qtp343564384-12147) [n:127.0.0.1:58697_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=2381
[junit4] 2> 954381 INFO (qtp1372685006-12148) [n:127.0.0.1:56081_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> 954456 INFO
(zkCallback-2024-thread-2-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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> 954457 INFO
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_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> 955381 INFO (qtp1372685006-12148) [n:127.0.0.1:56081_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=3551
[junit4] 2> 955387 INFO (qtp1372685006-12141) [n:127.0.0.1:56081_solr
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 955390 INFO (qtp1372685006-12141) [n:127.0.0.1:56081_solr
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33231/solr ready
[junit4] 2> 955406 INFO (qtp1372685006-12145) [n:127.0.0.1:56081_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> 955409 INFO (qtp343564384-12233) [n:127.0.0.1:58697_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> 955416 INFO (qtp1372685006-12141) [n:127.0.0.1:56081_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling/diagnostics
params={wt=javabin&version=2} status=0 QTime=33
[junit4] 2> 955423 INFO
(TEST-AutoScalingHandlerTest.testReadApi-seed#[B7F7EB47CDFAAA31]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testReadApi
[junit4] 2> 955439 INFO (jetty-closer-2014-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@1620eadc{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 955439 INFO (jetty-closer-2014-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=157377667
[junit4] 2> 955440 INFO (jetty-closer-2014-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 955440 INFO (jetty-closer-2014-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@3944beec{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 955441 INFO (jetty-closer-2014-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1292856144
[junit4] 2> 955441 INFO (jetty-closer-2014-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 955443 INFO (jetty-closer-2014-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 955444 INFO (jetty-closer-2014-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 955446 INFO (jetty-closer-2014-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 955447 INFO (jetty-closer-2014-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 955448 INFO (jetty-closer-2014-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 955449 INFO (jetty-closer-2014-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 955453 INFO (coreCloseExecutor-4150-thread-1)
[n:127.0.0.1:56081_solr c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n3] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n3] CLOSING SolrCore
org.apache.solr.core.SolrCore@809930e
[junit4] 2> 955453 INFO (coreCloseExecutor-4150-thread-2)
[n:127.0.0.1:56081_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@601a6bcb
[junit4] 2> 955455 INFO (coreCloseExecutor-4151-thread-1)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1 r:core_node1
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@62dfc3ef
[junit4] 2> 955466 INFO (coreCloseExecutor-4151-thread-2)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1 r:core_node4
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n4] CLOSING SolrCore
org.apache.solr.core.SolrCore@4f428baa
[junit4] 2> 955471 INFO (coreCloseExecutor-4150-thread-3)
[n:127.0.0.1:56081_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@5452606a
[junit4] 2> 955471 INFO (coreCloseExecutor-4151-thread-3)
[n:127.0.0.1:58697_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@5fe7c265
[junit4] 2> 955499 INFO (coreCloseExecutor-4150-thread-2)
[n:127.0.0.1:56081_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=1612344267
[junit4] 2> 955502 INFO (coreCloseExecutor-4150-thread-2)
[n:127.0.0.1:56081_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=1612344267
[junit4] 2> 955508 INFO (coreCloseExecutor-4150-thread-1)
[n:127.0.0.1:56081_solr c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n3, tag=134845198
[junit4] 2> 955511 INFO (coreCloseExecutor-4150-thread-1)
[n:127.0.0.1:56081_solr c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n3] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=134845198
[junit4] 2> 955519 INFO (coreCloseExecutor-4151-thread-2)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1 r:core_node4
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n4, tag=1329761194
[junit4] 2> 955522 INFO (coreCloseExecutor-4151-thread-2)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1 r:core_node4
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1329761194
[junit4] 2> 955527 INFO (coreCloseExecutor-4150-thread-3)
[n:127.0.0.1:56081_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=1414684778
[junit4] 2> 955530 INFO (coreCloseExecutor-4150-thread-3)
[n:127.0.0.1:56081_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=1414684778
[junit4] 2> 955533 INFO (coreCloseExecutor-4151-thread-3)
[n:127.0.0.1:58697_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=1609024101
[junit4] 2> 955536 INFO (coreCloseExecutor-4151-thread-3)
[n:127.0.0.1:58697_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=1609024101
[junit4] 2> 955543 INFO (coreCloseExecutor-4151-thread-1)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1 r:core_node1
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n2, tag=1658831855
[junit4] 2> 955546 INFO (coreCloseExecutor-4151-thread-1)
[n:127.0.0.1:58697_solr c:readApiTestViolations s:shard1 r:core_node1
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1658831855
[junit4] 2> 955551 WARN
(zkCallback-2025-thread-1-processing-n:127.0.0.1:58697_solr)
[n:127.0.0.1:58697_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 955552 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 0x15cc807ef740006, 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> 955553 INFO
(zkCallback-2024-thread-1-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 955559 INFO
(zkCallback-2024-thread-2-processing-n:127.0.0.1:56081_solr)
[n:127.0.0.1:56081_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: [1])
[junit4] 2> 955561 INFO (jetty-closer-2014-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98173228302991365-127.0.0.1:56081_solr-n_0000000000) closing
[junit4] 2> 955561 INFO
(OverseerStateUpdate-98173228302991365-127.0.0.1:56081_solr-n_0000000000)
[n:127.0.0.1:56081_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:56081_solr
[junit4] 2> 955563 INFO (jetty-closer-2014-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@28b87fef{/solr,null,UNAVAILABLE}
[junit4] 2> 955569 INFO (jetty-closer-2014-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@48469052{/solr,null,UNAVAILABLE}
[junit4] 2> 955572 ERROR
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-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> 955572 INFO
(SUITE-AutoScalingHandlerTest-seed#[B7F7EB47CDFAAA31]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:33231 33231
[junit4] 2> 955693 INFO (Thread-4192) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:33231 33231
[junit4] 2> 955693 WARN (Thread-4192) [ ] 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> 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> 8 /solr/clusterprops.json
[junit4] 2> 2
/solr/collections/readApiTestViolations/leader_elect/shard1/election/98173228302991365-core_node3-n_0000000000
[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:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_B7F7EB47CDFAAA31-001
[junit4] 2> Jun 21, 2017 12:23:06 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70),
sim=RandomSimilarity(queryNorm=false): {}, locale=it-CH, timezone=AGT
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation
1.8.0_131 (64-bit)/cpus=4,threads=1,free=304421880,total=533200896
[junit4] 2> NOTE: All tests run in this JVM: [ChaosMonkeySafeLeaderTest,
TestPerFieldSimilarity, TestDynamicLoading,
OverseerCollectionConfigSetProcessorTest, TestSolrConfigHandlerCloud,
MergeStrategyTest, TestJsonFacets, CollectionsAPIDistributedZkTest,
DocExpirationUpdateProcessorFactoryTest, TestQueryTypes, PrimUtilsTest,
TestSimpleTrackingShardHandler, TestSystemIdResolver, TestCloudRecovery,
DistribJoinFromCollectionTest, DistanceUnitsTest,
SchemaVersionSpecificBehaviorTest, MissingSegmentRecoveryTest,
IndexBasedSpellCheckerTest, TestFiltering, ReplicationFactorTest,
TestPartialUpdateDeduplication, TestFieldResource, TestBM25SimilarityFactory,
TestRestoreCore, HdfsDirectoryFactoryTest, TestStressVersions,
LeaderElectionContextKeyTest, LeaderInitiatedRecoveryOnCommitTest,
TestSolrCloudWithDelegationTokens, QueryParsingTest, TestSolrJ, TestLRUCache,
TestPostingsSolrHighlighter, TestHdfsBackupRestoreCore, TestConfigSets,
TestCoreContainer, V2ApiIntegrationTest, SimplePostToolTest, TestBulkSchemaAPI,
SSLMigrationTest, TestStressCloudBlindAtomicUpdates, TestHdfsUpdateLog,
TestInitQParser, TestSurroundQueryParser, SolrInfoBeanTest,
IndexSchemaRuntimeFieldTest, ScriptEngineTest, TestLegacyFieldCache,
BadComponentTest, TestPullReplica, TestRawTransformer,
AtomicUpdateProcessorFactoryTest, DistributedQueryComponentOptimizationTest,
SpellCheckComponentTest, TestSolr4Spatial2, TestPKIAuthenticationPlugin,
SolrMetricManagerTest, CdcrVersionReplicationTest, BasicFunctionalityTest,
TestFilteredDocIdSet, TestXIncludeConfig, FullHLLTest,
TestFileDictionaryLookup, InfixSuggestersTest, DistributedFacetPivotLargeTest,
LoggingHandlerTest, TestElisionMultitermQuery, TestSchemaVersionResource,
DistributedFacetPivotSmallTest, TestScoreJoinQPScore, SolrCmdDistributorTest,
ClusterStateUpdateTest, DocumentBuilderTest, TestSweetSpotSimilarityFactory,
SolrCLIZkUtilsTest, DistributedIntervalFacetingTest, TestSearchPerf,
TestRandomRequestDistribution, HdfsBasicDistributedZkTest,
TestFieldCacheSortRandom, WordBreakSolrSpellCheckerTest, HdfsRecoveryZkTest,
TestNonDefinedSimilarityFactory, TestCloudSchemaless, TestCloudNestedDocsSort,
HttpSolrCallGetCoreTest, TermsComponentTest, DateRangeFieldTest, SOLR749Test,
SpatialFilterTest, ManagedSchemaRoundRobinCloudTest, TestMissingGroups,
TlogReplayBufferedWhileIndexingTest, TestSubQueryTransformer,
BasicAuthStandaloneTest, TestHashPartitioner, BadCopyFieldTest,
TestShardHandlerFactory, DirectSolrConnectionTest, AutoScalingHandlerTest]
[junit4] Completed [313/725 (1!)] on J1 in 4.90s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 44600 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
