Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/317/
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi
Error Message:
expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
at
__randomizedtesting.SeedInfo.seed([75C131060C852E87:22E8CAB3D777CC9C]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testReadApi(AutoScalingHandlerTest.java:725)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12659 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/init-core-data-001
[junit4] 2> 2193689 WARN
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 2193710 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2193711 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2193712 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2193712 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001
[junit4] 2> 2193712 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2193712 INFO (Thread-1839) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2193712 INFO (Thread-1839) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2193752 ERROR (Thread-1839) [ ] 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> 2193819 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:35315
[junit4] 2> 2194046 INFO (zkConnectionManagerCallback-2441-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2194170 INFO (jetty-launcher-2438-thread-1) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2194391 INFO (jetty-launcher-2438-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@44a77e0{/solr,null,AVAILABLE}
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5b418e5a{SSL,[ssl,
http/1.1]}{127.0.0.1:41864}
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.e.j.s.Server Started @2203742ms
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41864}
[junit4] 2> 2194392 ERROR (jetty-launcher-2438-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2194392 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-16T21:23:03.571Z
[junit4] 2> 2194436 INFO (jetty-launcher-2438-thread-2) [ ]
o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 2194496 INFO (jetty-launcher-2438-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3375abbd{/solr,null,AVAILABLE}
[junit4] 2> 2194496 INFO (jetty-launcher-2438-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@5d3f40ce{SSL,[ssl,
http/1.1]}{127.0.0.1:43663}
[junit4] 2> 2194496 INFO (jetty-launcher-2438-thread-2) [ ]
o.e.j.s.Server Started @2203847ms
[junit4] 2> 2194496 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43663}
[junit4] 2> 2194497 ERROR (jetty-launcher-2438-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2194497 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.3.0
[junit4] 2> 2194497 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2194497 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2194497 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-01-16T21:23:03.676Z
[junit4] 2> 2194544 INFO (zkConnectionManagerCallback-2443-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2194544 INFO (zkConnectionManagerCallback-2445-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2194553 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2194553 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2194743 INFO (jetty-launcher-2438-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35315/solr
[junit4] 2> 2194799 INFO (zkConnectionManagerCallback-2449-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2194824 INFO (jetty-launcher-2438-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:35315/solr
[junit4] 2> 2194852 INFO (zkConnectionManagerCallback-2453-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2194985 INFO
(zkConnectionManagerCallback-2455-thread-1-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2194997 INFO
(zkConnectionManagerCallback-2457-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2195536 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2195537 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:43663_solr
[junit4] 2> 2195537 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.c.Overseer Overseer
(id=99361605739610118-127.0.0.1:43663_solr-n_0000000000) starting
[junit4] 2> 2195601 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43663_solr
[junit4] 2> 2195873 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2196041 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43663.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2196078 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43663.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2196091 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43663.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2196092 INFO (jetty-launcher-2438-thread-2)
[n:127.0.0.1:43663_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/.
[junit4] 2> 2196152 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2196153 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2196155 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41864_solr
[junit4] 2> 2196168 INFO
(zkCallback-2456-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2196274 INFO
(zkCallback-2454-thread-1-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2196522 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41864.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2196574 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41864.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2196574 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41864.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2196575 INFO (jetty-launcher-2438-thread-1)
[n:127.0.0.1:41864_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/.
[junit4] 2> 2196732 INFO (zkConnectionManagerCallback-2463-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2196734 INFO (zkConnectionManagerCallback-2467-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2196735 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2196751 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:35315/solr ready
[junit4] 2> 2196859 INFO
(TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[75C131060C852E87]) [
] o.a.s.SolrTestCaseJ4 ###Starting testDeleteUsedPolicy
[junit4] 2> 2197223 INFO (qtp1964390929-10760) [n:127.0.0.1:41864_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=2
[junit4] 2> 2197268 INFO (qtp2066369429-10772) [n:127.0.0.1:43663_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> 2197282 INFO (qtp2066369429-10770) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=197
[junit4] 2> 2197284 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete
and sendToOCPQueue=true
[junit4] 2> 2197286 INFO
(OverseerThreadFactory-3840-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.CreateCollectionCmd Create collection COLL1
[junit4] 2> 2197346 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=3
[junit4] 2> 2197404 INFO (qtp2066369429-10765) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2197426 INFO
(OverseerThreadFactory-3840-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 592118598 sessionWrapper.createTime 592118577050542,
this.sessionWrapper.createTime 592118577050542
[junit4] 2> 2197586 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"COLL1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"COLL1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43663/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2197815 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=COLL1_shard1_replica_n1&action=CREATE&numShards=1&collection=COLL1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2197815 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2197959 INFO
(zkCallback-2456-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2197983 INFO
(zkCallback-2456-thread-2-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2198945 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.3.0
[junit4] 2> 2198989 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.IndexSchema
[COLL1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2198991 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2198991 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'COLL1_shard1_replica_n1' using configuration from collection
COLL1, trusted=true
[junit4] 2> 2199008 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43663.solr.core.COLL1.shard1.replica_n1' (registry
'solr.core.COLL1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2199008 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2199008 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore
[[COLL1_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/COLL1_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/./COLL1_shard1_replica_n1/data/]
[junit4] 2> 2199257 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2199257 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2199258 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2199258 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2199272 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6ad16831[COLL1_shard1_replica_n1]
main]
[junit4] 2> 2199304 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2199304 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2199304 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2199305 INFO
(searcherExecutor-3845-thread-1-processing-n:127.0.0.1:43663_solr
x:COLL1_shard1_replica_n1 s:shard1 c:COLL1 r:core_node2)
[n:127.0.0.1:43663_solr c:COLL1 s:shard1 r:core_node2
x:COLL1_shard1_replica_n1] o.a.s.c.SolrCore [COLL1_shard1_replica_n1]
Registered new searcher Searcher@6ad16831[COLL1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2199305 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1589785697697398784
[junit4] 2> 2199381 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2199381 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2199381 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:43663/solr/COLL1_shard1_replica_n1/
[junit4] 2> 2199381 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 2199381 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.SyncStrategy
https://127.0.0.1:43663/solr/COLL1_shard1_replica_n1/ has no replicas
[junit4] 2> 2199381 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2199401 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:43663/solr/COLL1_shard1_replica_n1/ shard1
[junit4] 2> 2199575 INFO
(zkCallback-2456-thread-2-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2199575 INFO
(zkCallback-2456-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2199611 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 2199613 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:COLL1 s:shard1 r:core_node2 x:COLL1_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=COLL1_shard1_replica_n1&action=CREATE&numShards=1&collection=COLL1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1798
[junit4] 2> 2199629 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_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> 2199733 INFO
(zkCallback-2456-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2199733 INFO
(zkCallback-2456-thread-2-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/COLL1/state.json] for collection [COLL1] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 2200629 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=COLL1&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2&policy=nodelete}
status=0 QTime=3345
[junit4] 2> 2200633 ERROR (qtp2066369429-10769) [n:127.0.0.1:43663_solr
] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject:
Error in command payload, errors: [{remove-policy=nodelete,
errorMessages=[policy : nodelete is being used by collection COLL1]}],
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:311)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[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:1759)
[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:426)
[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:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[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> 2200633 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=400 QTime=1
[junit4] 2> 2200634 INFO (qtp2066369429-10772) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=COLL1&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2200640 INFO
(OverseerCollectionConfigSetProcessor-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 2200641 INFO
(OverseerThreadFactory-3840-thread-2-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 2200641 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.COLL1.shard1.replica_n1, tag=null
[junit4] 2> 2200641 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1352bac3: rootName =
solr_43663, domain = solr.core.COLL1.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.COLL1.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@625e23b9
[junit4] 2> 2200684 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.c.SolrCore [COLL1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@7db03a62
[junit4] 2> 2200684 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.COLL1.shard1.replica_n1, tag=2108701282
[junit4] 2> 2200684 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.COLL1.shard1.leader, tag=2108701282
[junit4] 2> 2200707 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=COLL1_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=65
[junit4] 2> 2201441 INFO (qtp2066369429-10772) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=COLL1&action=DELETE&wt=javabin&version=2} status=0 QTime=806
[junit4] 2> 2201441 INFO
(TEST-AutoScalingHandlerTest.testDeleteUsedPolicy-seed#[75C131060C852E87]) [
] o.a.s.SolrTestCaseJ4 ###Ending testDeleteUsedPolicy
[junit4] 2> 2201465 INFO
(TEST-AutoScalingHandlerTest.testErrorHandling-seed#[75C131060C852E87]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testErrorHandling
[junit4] 2> 2201467 ERROR (qtp2066369429-10770) [n:127.0.0.1:43663_solr
] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject:
Error in command payload, errors: [{set-cluster-policy=[{cores=<10, node=#ANY},
{shard=#EACH, node=#ANY}, {nodeRole=overseer, replica=0}],
errorMessages=[replica is required in {
[junit4] 2> "shard":"#EACH",
[junit4] 2> "node":"#ANY"}]}],
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:207)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:145)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[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:1759)
[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:426)
[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:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at
org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[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> 2201468 INFO (qtp2066369429-10770) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=400 QTime=2
[junit4] 2> 2201468 INFO
(TEST-AutoScalingHandlerTest.testErrorHandling-seed#[75C131060C852E87]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testErrorHandling
[junit4] 2> 2201496 INFO
(TEST-AutoScalingHandlerTest.testReadApi-seed#[75C131060C852E87]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 2201514 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 2201528 INFO (qtp1964390929-10759) [n:127.0.0.1:41864_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=12
[junit4] 2> 2201580 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=68
[junit4] 2> 2201605 INFO (qtp2066369429-10772) [n:127.0.0.1:43663_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> 2201606 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_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> 2201607 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 2201616 INFO (qtp2066369429-10772) [n:127.0.0.1:43663_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> 2201617 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_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> 2201618 INFO (qtp1964390929-10757) [n:127.0.0.1:41864_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 2201620 INFO (qtp2066369429-10765) [n:127.0.0.1:43663_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> 2201633 INFO (qtp1964390929-10760) [n:127.0.0.1:41864_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=12
[junit4] 2> 2201637 INFO (qtp2066369429-10770) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=18
[junit4] 2> 2201639 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2201654 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_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> 2201657 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_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> 2201670 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/cluster/autoscaling/diagnostics params={wt=javabin&version=2} status=0
QTime=29
[junit4] 2> 2201670 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=6&collection.configName=conf&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2201676 INFO
(OverseerThreadFactory-3840-thread-3-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.CreateCollectionCmd Create collection
readApiTestViolations
[junit4] 2> 2201676 INFO
(OverseerCollectionConfigSetProcessor-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 2201677 ERROR
(OverseerThreadFactory-3840-thread-3-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.OverseerCollectionMessageHandler
Collection: readApiTestViolations operation: create
failed:org.apache.solr.common.SolrException: 'maxShardsPerNode>0' is not
supported when autoScaling policies are used
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.buildReplicaPositions(CreateCollectionCmd.java:294)
[junit4] 2> at
org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:123)
[junit4] 2> at
org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:243)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 2201698 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=6&collection.configName=conf&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2}
status=400 QTime=28
[junit4] 2> 2201701 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_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> 2201710 INFO (qtp1964390929-10759) [n:127.0.0.1:41864_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> 2201711 INFO (qtp2066369429-10772) [n:127.0.0.1:43663_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=11
[junit4] 2> 2201712 INFO (qtp2066369429-10765) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=6&collection.configName=conf&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2201720 INFO
(OverseerThreadFactory-3840-thread-4-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.CreateCollectionCmd Create collection
readApiTestViolations
[junit4] 2> 2201720 INFO
(OverseerCollectionConfigSetProcessor-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 2201721 WARN
(OverseerThreadFactory-3840-thread-4-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_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> 2201726 INFO (qtp2066369429-10767) [n:127.0.0.1:43663_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> 2201743 INFO (qtp1964390929-10755) [n:127.0.0.1:41864_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> 2201766 INFO
(OverseerThreadFactory-3840-thread-4-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession,
curr-time 592122938 sessionWrapper.createTime 592122915211723,
this.sessionWrapper.createTime 592122915211723
[junit4] 2> 2201995 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43663/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2201997 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41864/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2202006 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43663/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2202008 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41864/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2202009 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:43663/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2202027 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"readApiTestViolations",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"readApiTestViolations_shard1_replica_n10",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41864/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2202228 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&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> 2202230 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node5&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> 2202230 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for
2147483647 transient cores
[junit4] 2> 2202232 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node7&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> 2202233 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n6&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2202233 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n8&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2202237 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n10&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2202370 INFO
(zkCallback-2454-thread-1-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_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> 2202370 INFO
(zkCallback-2456-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_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> 2202371 INFO
(zkCallback-2454-thread-2-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_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> 2202386 INFO
(zkCallback-2456-thread-3-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_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> 2203376 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 2203392 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 2203410 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 2203449 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n10] Schema name=minimal
[junit4] 2> 2203449 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 2203451 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2203451 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n10' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 2203465 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n4] Schema name=minimal
[junit4] 2> 2203467 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2203467 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
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> 2203469 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n6] Schema name=minimal
[junit4] 2> 2203470 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2203471 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
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> 2203472 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_41864.solr.core.readApiTestViolations.shard1.replica_n10'
(registry 'solr.core.readApiTestViolations.shard1.replica_n10') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2203472 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2203472 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n10] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n10],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n10/data/]
[junit4] 2> 2203477 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_43663.solr.core.readApiTestViolations.shard1.replica_n4'
(registry 'solr.core.readApiTestViolations.shard1.replica_n4') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2203477 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2203477 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n4] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n4],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n4/data/]
[junit4] 2> 2203492 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_41864.solr.core.readApiTestViolations.shard1.replica_n6'
(registry 'solr.core.readApiTestViolations.shard1.replica_n6') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2203492 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2203492 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n6] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n6],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n6/data/]
[junit4] 2> 2203506 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 2203701 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 2203749 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n2] Schema name=minimal
[junit4] 2> 2203750 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2203751 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2203751 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
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> 2203764 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_41864.solr.core.readApiTestViolations.shard1.replica_n2'
(registry 'solr.core.readApiTestViolations.shard1.replica_n2') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2203764 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2203764 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n2/data/]
[junit4] 2> 2203817 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n8] Schema name=minimal
[junit4] 2> 2203819 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2203819 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.CoreContainer Creating
SolrCore 'readApiTestViolations_shard1_replica_n8' using configuration from
collection readApiTestViolations, trusted=true
[junit4] 2> 2203820 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
minimal/1.1 with uniqueid field id
[junit4] 2> 2203820 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
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> 2203832 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_43663.solr.core.readApiTestViolations.shard1.replica_n1'
(registry 'solr.core.readApiTestViolations.shard1.replica_n1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2203832 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2203832 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n1/data/]
[junit4] 2> 2203869 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_43663.solr.core.readApiTestViolations.shard1.replica_n8'
(registry 'solr.core.readApiTestViolations.shard1.replica_n8') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@51a9e114
[junit4] 2> 2203869 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2203869 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n8] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n8],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n8/data/]
[junit4] 2> 2204286 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2204286 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2204287 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2204287 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2204288 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.s.SolrIndexSearcher Opening
[Searcher@39c8a774[readApiTestViolations_shard1_replica_n4] main]
[junit4] 2> 2204343 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2204359 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2204371 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2204371 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2204371 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 2204372 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1589785703010533376
[junit4] 2> 2204372 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2204372 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2204373 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2204373 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2204374 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2204374 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2204375 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.ShardLeaderElectionContext
Waiting until we see more replicas up for shard shard1: total=6 found=1
timeoutin=9999ms
[junit4] 2> 2204380 INFO
(searcherExecutor-3851-thread-1-processing-n:127.0.0.1:43663_solr
x:readApiTestViolations_shard1_replica_n4 s:shard1 c:readApiTestViolations
r:core_node7) [n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1
r:core_node7 x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n4] Registered new searcher
Searcher@39c8a774[readApiTestViolations_shard1_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2204380 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.s.SolrIndexSearcher Opening
[Searcher@25b2c56c[readApiTestViolations_shard1_replica_n10] main]
[junit4] 2> 2204400 INFO
(zkCallback-2456-thread-2-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_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> 2204400 INFO
(zkCallback-2456-thread-3-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_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> 2204404 INFO
(zkCallback-2454-thread-2-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_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> 2204404 INFO
(zkCallback-2454-thread-1-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_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> 2204416 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2204416 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2204417 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2204417 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2204418 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2204418 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2204419 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2204419 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2204428 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.s.SolrIndexSearcher Opening
[Searcher@31642988[readApiTestViolations_shard1_replica_n6] main]
[junit4] 2> 2204436 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@619a949e[readApiTestViolations_shard1_replica_n1] main]
[junit4] 2> 2204437 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.s.SolrIndexSearcher Opening
[Searcher@14337e68[readApiTestViolations_shard1_replica_n8] main]
[junit4] 2> 2204444 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2204444 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2204445 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 2204445 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 2204446 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@622bb469[readApiTestViolations_shard1_replica_n2] main]
[junit4] 2> 2204453 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2204453 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2204453 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2204453 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 2204454 INFO (qtp2066369429-10771) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1589785703096516608
[junit4] 2> 2204456 INFO
(searcherExecutor-3855-thread-1-processing-n:127.0.0.1:43663_solr
x:readApiTestViolations_shard1_replica_n1 s:shard1 c:readApiTestViolations
r:core_node3) [n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1
r:core_node3 x:readApiTestViolations_shard1_replica_n1] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n1] Registered new searcher
Searcher@619a949e[readApiTestViolations_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2204456 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2204457 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 2204457 INFO (qtp2066369429-10769) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1589785703099662336
[junit4] 2> 2204459 INFO
(searcherExecutor-3854-thread-1-processing-n:127.0.0.1:43663_solr
x:readApiTestViolations_shard1_replica_n8 s:shard1 c:readApiTestViolations
r:core_node11) [n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1
r:core_node11 x:readApiTestViolations_shard1_replica_n8] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n8] Registered new searcher
Searcher@14337e68[readApiTestViolations_shard1_replica_n8]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2204468 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2204468 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2204469 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 2204469 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2204470 INFO
(searcherExecutor-3850-thread-1-processing-n:127.0.0.1:41864_solr
x:readApiTestViolations_shard1_replica_n10 s:shard1 c:readApiTestViolations
r:core_node12) [n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1
r:core_node12 x:readApiTestViolations_shard1_replica_n10] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n10] Registered new searcher
Searcher@25b2c56c[readApiTestViolations_shard1_replica_n10]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2204470 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2204470 INFO (qtp1964390929-10761) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node12
x:readApiTestViolations_shard1_replica_n10] o.a.s.u.UpdateLog Could not find
max version in index or recent updates, using new clock 1589785703113293824
[junit4] 2> 2204471 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2204472 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 2204472 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.r.ManagedResourceStorage
Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2204606 INFO
(searcherExecutor-3852-thread-1-processing-n:127.0.0.1:41864_solr
x:readApiTestViolations_shard1_replica_n6 s:shard1 c:readApiTestViolations
r:core_node9) [n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1
r:core_node9 x:readApiTestViolations_shard1_replica_n6] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n6] Registered new searcher
Searcher@31642988[readApiTestViolations_shard1_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2204606 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 2204607 INFO (qtp1964390929-10758) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1589785703256948736
[junit4] 2> 2204609 INFO (qtp1964390929-10754) [n:127.0.0.1:41864_solr
c:readApiTestViolations s:shard1 r:core_node9
x:readApiTestViolations_shard1_replica_n6] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1589785703259045888
[junit4] 2> 2204620 INFO
(searcherExecutor-3853-thread-1-processing-n:127.0.0.1:41864_solr
x:readApiTestViolations_shard1_replica_n2 s:shard1 c:readApiTestViolations
r:core_node5) [n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1
r:core_node5 x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n2] Registered new searcher
Searcher@622bb469[readApiTestViolations_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2204876 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 2204876 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 2204876 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:43663/solr/readApiTestViolations_shard1_replica_n4/
[junit4] 2> 2204876 INFO (qtp2066369429-10766) [n:127.0.0.1:43663_solr
c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.u.PeerSync PeerSync:
core=readApiTestViolations_shard1_replica_n4 url=https://127.0.0.1:43663/solr
START
replicas=[https://127.0.0.1:43663/solr/readApiTestViolations_shard1_replica_n1/,
https://127.0.0.1:41864/solr/readApiTestViolations_shard1_replica_n2/,
https://127.0.0.1:41864/solr/readApiTestViolations_shard1_replica_n6/,
https://127.0.0.1:43663/solr/readApiTestViolations_shard1_replica_n8/,
https://127.0.0.1:41864/solr/readApiTestViolations_shard
[...truncated too long message...]
ApiTestViolations] has occurred - updating... (live nodes size: [0])
[junit4] 2> 2213370 INFO (coreCloseExecutor-3888-thread-2)
[n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.c.SolrCore
[readApiTestViolations_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@23932760
[junit4] 2> 2213370 INFO (coreCloseExecutor-3888-thread-2)
[n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n2, tag=596846432
[junit4] 2> 2213370 INFO (coreCloseExecutor-3888-thread-2)
[n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15a5ebb3: rootName
= solr_41864, domain = solr.core.readApiTestViolations.shard1.replica_n2,
service url = null, agent id = null] for registry
solr.core.readApiTestViolations.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@36bb95a6
[junit4] 2> 2213370 INFO
(zkCallback-2454-thread-3-processing-n:127.0.0.1:41864_solr)
[n:127.0.0.1:41864_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: [0])
[junit4] 2> 2213371 INFO (jetty-closer-2439-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@27e49c02{/solr,null,UNAVAILABLE}
[junit4] 2> 2213380 INFO
(zkCallback-2456-thread-1-processing-n:127.0.0.1:43663_solr)
[n:127.0.0.1:43663_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: [0])
[junit4] 2> 2213406 INFO (coreCloseExecutor-3886-thread-2)
[n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1 r:core_node3
x:readApiTestViolations_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=1860750354
[junit4] 2> 2213406 INFO (coreCloseExecutor-3886-thread-1)
[n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1 r:core_node7
x:readApiTestViolations_shard1_replica_n4] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=541773479
[junit4] 2> 2213406 INFO (coreCloseExecutor-3886-thread-3)
[n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n8, tag=651912070
[junit4] 2> 2213406 INFO (coreCloseExecutor-3886-thread-3)
[n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.m.r.SolrJmxReporter Closing
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33aa9b5c: rootName
= solr_43663, domain = solr.core.readApiTestViolations.shard1.replica_n8,
service url = null, agent id = null] for registry
solr.core.readApiTestViolations.shard1.replica_n8 /
com.codahale.metrics.MetricRegistry@4cc67e32
[junit4] 2> 2213440 INFO (coreCloseExecutor-3888-thread-2)
[n:127.0.0.1:41864_solr c:readApiTestViolations s:shard1 r:core_node5
x:readApiTestViolations_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=596846432
[junit4] 2> 2213474 INFO (coreCloseExecutor-3886-thread-3)
[n:127.0.0.1:43663_solr c:readApiTestViolations s:shard1 r:core_node11
x:readApiTestViolations_shard1_replica_n8] o.a.s.m.SolrMetricManager Closing
metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=651912070
[junit4] 2> 2213535 INFO (jetty-closer-2439-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=99361605739610118-127.0.0.1:43663_solr-n_0000000000) closing
[junit4] 2> 2213551 INFO
(OverseerStateUpdate-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:43663_solr
[junit4] 2> 2213583 WARN
(OverseerAutoScalingTriggerThread-99361605739610118-127.0.0.1:43663_solr-n_0000000000)
[n:127.0.0.1:43663_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2213676 INFO (jetty-closer-2439-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@44a77e0{/solr,null,UNAVAILABLE}
[junit4] 2> 2215171 INFO (jetty-closer-2439-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3375abbd{/solr,null,UNAVAILABLE}
[junit4] 2> 2215172 ERROR
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-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> 2215172 INFO
(SUITE-AutoScalingHandlerTest-seed#[75C131060C852E87]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:35315 35315
[junit4] 2> 2215483 INFO (Thread-1839) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35315 35315
[junit4] 2> 2215483 WARN (Thread-1839) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18
/solr/collections/readApiTestViolations/state.json
[junit4] 2> 5 /solr/collections/COLL1/state.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/autoscaling/events/node_lost_trigger
[junit4] 2> 2 /solr/autoscaling/events/node_added_trigger
[junit4] 2> 2 /solr/autoscaling/events/node_added_trigger1
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_75C131060C852E87-001
[junit4] 2> Jan 16, 2018 9:23:24 PM
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): {},
docValues:{}, maxPointsInLeafNode=747, maxMBSortInHeap=6.8184077987319505,
sim=RandomSimilarity(queryNorm=true): {}, locale=fi, timezone=Asia/Shanghai
[junit4] 2> NOTE: Linux 4.4.0-104-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=1,free=294681568,total=522190848
[junit4] 2> NOTE: All tests run in this JVM: [MetricsConfigTest,
TestCloudRecovery, TestHalfAndHalfDocValues, TestJmxIntegration,
TestNumericTerms64, DistributedVersionInfoTest, TestExecutePlanAction,
TestManagedSynonymFilterFactory, HdfsBasicDistributedZk2Test,
TestReloadAndDeleteDocs, TestOmitPositions, TestSubQueryTransformer,
TestMultiWordSynonyms, TlogReplayBufferedWhileIndexingTest,
TestQuerySenderNoQuery, BooleanFieldTest, ZkSolrClientTest, TestRestoreCore,
ZkFailoverTest, TestExpandComponent, TestTolerantUpdateProcessorCloud,
CollectionTooManyReplicasTest, TestStressInPlaceUpdates,
TestHdfsBackupRestoreCore, ExitableDirectoryReaderTest,
SuggestComponentContextFilterQueryTest, SimpleMLTQParserTest, ShardSplitTest,
TestRandomCollapseQParserPlugin, TestBinaryResponseWriter, TestManagedResource,
CircularListTest, ImplicitSnitchTest, TestManagedSchemaThreadSafety,
TestSurroundQueryParser, TestSolrCoreSnapshots,
TestLegacyNumericRangeQueryBuilder, ConvertedLegacyTest, TestCustomSort,
WrapperMergePolicyFactoryTest, TestSizeLimitedDistributedMap, TestRTimerTree,
PolyFieldTest, TestBulkSchemaAPI, TestApiFramework, TestSolrConfigHandlerCloud,
OutputWriterTest, TestSolrCloudWithKerberosAlt, TestConfigSetsAPIExclusivity,
PeerSyncWithIndexFingerprintCachingTest, ReplaceNodeTest,
OverriddenZkACLAndCredentialsProvidersTest, ConjunctionSolrSpellCheckerTest,
TestCustomStream, TestSolr4Spatial, SolrGraphiteReporterTest, DOMUtilTest,
TestStressUserVersions, SolrMetricReporterTest, OpenCloseCoreStressTest,
SolrIndexConfigTest, NumericFieldsTest, ShardRoutingCustomTest, TestLockTree,
BufferStoreTest, SynonymTokenizerTest, TestIntervalFaceting,
TestSimDistributedQueue, DirectUpdateHandlerTest, TestCloudJSONFacetJoinDomain,
TestDefaultStatsCache, TestLuceneMatchVersion,
AddSchemaFieldsUpdateProcessorFactoryTest,
BigEndianAscendingWordSerializerTest, TestGeoJSONResponseWriter,
LukeRequestHandlerTest, TestFastWriter, BasicAuthStandaloneTest, SampleTest,
ConfigureRecoveryStrategyTest, TestInfoStreamLogging, TestFastLRUCache,
TestHighFrequencyDictionaryFactory, SolrCmdDistributorTest,
PreAnalyzedFieldTest, TestCollationField, ReplicaListTransformerTest,
CdcrBidirectionalTest, TestAnalyzedSuggestions, TestInPlaceUpdatesStandalone,
TestRangeQuery, TestDelegationWithHadoopAuth, TolerantUpdateProcessorTest,
TestAuthenticationFramework, SuggesterTSTTest, TestSimpleTrackingShardHandler,
HdfsUnloadDistributedZkTest, OpenExchangeRatesOrgProviderTest,
AnalyticsMergeStrategyTest, TestDocTermOrds, AutoScalingHandlerTest]
[junit4] Completed [288/767 (1!)] on J1 in 22.34s, 8 tests, 1 failure <<<
FAILURES!
[...truncated 49783 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
