Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19914/
Java: 32bit/jdk-9-ea+173 -client -XX:+UseParallelGC
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences
Error Message:
Adding a policy with 'cores' attribute should not have succeeded.
Stack Trace:
java.lang.AssertionError: Adding a policy with 'cores' attribute should not
have succeeded.
at
__randomizedtesting.SeedInfo.seed([DB220AFF163D3283:7AEA54D66F7B348D]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12761 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/init-core-data-001
[junit4] 2> 1955982 WARN
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=136 numCloses=136
[junit4] 2> 1955982 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 1955983 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 1955983 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001
[junit4] 2> 1955983 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1955983 INFO (Thread-3589) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1955983 INFO (Thread-3589) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1955984 ERROR (Thread-3589) [ ] 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> 1956083 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:32943
[junit4] 2> 1956088 INFO (jetty-launcher-5872-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1956088 INFO (jetty-launcher-5872-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1956094 INFO (jetty-launcher-5872-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@462c67{/solr,null,AVAILABLE}
[junit4] 2> 1956094 INFO (jetty-launcher-5872-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6748e7{/solr,null,AVAILABLE}
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@958865{HTTP/1.1,[http/1.1]}{127.0.0.1:43093}
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1213500{HTTP/1.1,[http/1.1]}{127.0.0.1:33405}
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-2) [ ]
o.e.j.s.Server Started @1958164ms
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-1) [ ]
o.e.j.s.Server Started @1958164ms
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=43093}
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33405}
[junit4] 2> 1956095 ERROR (jetty-launcher-5872-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1956095 ERROR (jetty-launcher-5872-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 1956095 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 1956096 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1956096 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1956096 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1956096 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1956096 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-20T09:26:32.360282Z
[junit4] 2> 1956096 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-20T09:26:32.360353Z
[junit4] 2> 1956098 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1956098 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1956105 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32943/solr
[junit4] 2> 1956105 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32943/solr
[junit4] 2> 1956121 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1956121 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1956121 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33405_solr
[junit4] 2> 1956121 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:43093_solr
[junit4] 2> 1956121 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98169703120764934-127.0.0.1:33405_solr-n_0000000000) starting
[junit4] 2> 1956122 INFO (zkCallback-5884-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1956125 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33405_solr
[junit4] 2> 1956126 INFO (zkCallback-5884-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1956132 INFO (zkCallback-5883-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1956172 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33405.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1956175 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33405.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1956175 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33405.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1956176 INFO (jetty-launcher-5872-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/.
[junit4] 2> 1956203 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43093.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1956207 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43093.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1956207 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43093.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1956208 INFO (jetty-launcher-5872-thread-2) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/.
[junit4] 2> 1956223 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1956224 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32943/solr ready
[junit4] 2> 1956232 INFO
(TEST-AutoScalingHandlerTest.testReadApi-seed#[DB220AFF163D3283]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testReadApi
[junit4] 2> 1956235 INFO (qtp33527590-27585) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1956235 INFO (qtp33527590-27585) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32943/solr ready
[junit4] 2> 1956236 INFO (qtp33527590-27589) [ ] 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> 1956236 INFO (qtp14165054-27582) [ ] 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> 1956237 INFO (qtp33527590-27585) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2}
status=0 QTime=4
[junit4] 2> 1956239 INFO (qtp14165054-27574) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1956240 INFO (qtp14165054-27574) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32943/solr ready
[junit4] 2> 1956241 INFO (qtp33527590-27577) [ ] 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> 1956242 INFO (qtp14165054-27573) [ ] 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> 1956244 INFO (qtp14165054-27574) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 1956249 INFO (qtp33527590-27587) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1956250 INFO (qtp33527590-27587) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32943/solr ready
[junit4] 2> 1956251 INFO (qtp33527590-27575) [ ] 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> 1956252 INFO (qtp14165054-27584) [ ] 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> 1956252 INFO (qtp33527590-27587) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2}
status=0 QTime=4
[junit4] 2> 1956253 INFO (qtp33527590-27585) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1956257 INFO (qtp33527590-27577) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1956257 INFO (qtp33527590-27577) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32943/solr ready
[junit4] 2> 1956258 INFO (qtp33527590-27579) [ ] 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> 1956259 INFO (qtp14165054-27576) [ ] 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> 1956259 INFO (qtp33527590-27577) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/autoscaling/diagnostics
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1956260 INFO (qtp33527590-27575) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
pullReplicas=0&replicationFactor=6&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1956260 INFO (OverseerThreadFactory-8575-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection readApiTestViolations
[junit4] 2> 1956260 INFO (OverseerThreadFactory-8575-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Only one config set found in zk - using it:conf
[junit4] 2> 1956263 WARN (OverseerThreadFactory-8575-thread-1) [ ]
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> 1956366 INFO (qtp33527590-27581) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1956366 INFO (qtp14165054-27578) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n2&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1956366 INFO (qtp14165054-27586) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n4&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1956366 INFO (qtp33527590-27581) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1956366 INFO (qtp33527590-27587) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1956366 INFO (qtp14165054-27578) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 1956366 INFO (qtp14165054-27584) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n6&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1956367 INFO (qtp33527590-27579) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1956469 INFO (zkCallback-5884-thread-1) [ ]
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> 1956469 INFO (zkCallback-5883-thread-1) [ ]
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> 1957382 INFO (qtp14165054-27584) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1957383 INFO (qtp33527590-27579) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1957383 INFO (qtp33527590-27581) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1957383 INFO (qtp14165054-27586) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1957388 INFO (qtp33527590-27587) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1957388 INFO (qtp14165054-27578) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1957399 INFO (qtp14165054-27586) [ ] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n4] Schema name=minimal
[junit4] 2> 1957401 INFO (qtp14165054-27586) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1957401 INFO (qtp14165054-27586) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'readApiTestViolations_shard1_replica_n4' using configuration
from collection readApiTestViolations, trusted=true
[junit4] 2> 1957401 INFO (qtp14165054-27586) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33405.solr.core.readApiTestViolations.shard1.replica_n4' (registry
'solr.core.readApiTestViolations.shard1.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1957401 INFO (qtp14165054-27586) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1957401 INFO (qtp14165054-27586) [ ] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n4] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n4],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n4/data/]
[junit4] 2> 1957402 INFO (qtp14165054-27584) [ ] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n6] Schema name=minimal
[junit4] 2> 1957403 INFO (qtp14165054-27584) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1957403 INFO (qtp14165054-27584) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'readApiTestViolations_shard1_replica_n6' using configuration
from collection readApiTestViolations, trusted=true
[junit4] 2> 1957403 INFO (qtp14165054-27584) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33405.solr.core.readApiTestViolations.shard1.replica_n6' (registry
'solr.core.readApiTestViolations.shard1.replica_n6') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1957403 INFO (qtp14165054-27584) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1957403 INFO (qtp14165054-27584) [ ] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n6] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n6],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n6/data/]
[junit4] 2> 1957406 INFO (qtp33527590-27581) [ ] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n3] Schema name=minimal
[junit4] 2> 1957406 INFO (qtp14165054-27578) [ ] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1957406 INFO (qtp33527590-27579) [ ] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n5] Schema name=minimal
[junit4] 2> 1957407 INFO (qtp33527590-27581) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1957407 INFO (qtp33527590-27581) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'readApiTestViolations_shard1_replica_n3' using configuration
from collection readApiTestViolations, trusted=true
[junit4] 2> 1957408 INFO (qtp33527590-27587) [ ] o.a.s.s.IndexSchema
[readApiTestViolations_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1957408 INFO (qtp14165054-27578) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1957408 INFO (qtp14165054-27578) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'readApiTestViolations_shard1_replica_n2' using configuration
from collection readApiTestViolations, trusted=true
[junit4] 2> 1957408 INFO (qtp33527590-27579) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1957408 INFO (qtp33527590-27579) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'readApiTestViolations_shard1_replica_n5' using configuration
from collection readApiTestViolations, trusted=true
[junit4] 2> 1957408 INFO (qtp33527590-27581) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43093.solr.core.readApiTestViolations.shard1.replica_n3' (registry
'solr.core.readApiTestViolations.shard1.replica_n3') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1957408 INFO (qtp14165054-27578) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_33405.solr.core.readApiTestViolations.shard1.replica_n2' (registry
'solr.core.readApiTestViolations.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1957408 INFO (qtp33527590-27579) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43093.solr.core.readApiTestViolations.shard1.replica_n5' (registry
'solr.core.readApiTestViolations.shard1.replica_n5') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1957408 INFO (qtp14165054-27578) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1957408 INFO (qtp33527590-27579) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1957408 INFO (qtp14165054-27578) [ ] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/readApiTestViolations_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node1/./readApiTestViolations_shard1_replica_n2/data/]
[junit4] 2> 1957408 INFO (qtp33527590-27581) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1957408 INFO (qtp33527590-27581) [ ] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n3/data/]
[junit4] 2> 1957408 INFO (qtp33527590-27579) [ ] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n5] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n5],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n5/data/]
[junit4] 2> 1957409 INFO (qtp33527590-27587) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1957409 INFO (qtp33527590-27587) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'readApiTestViolations_shard1_replica_n1' using configuration
from collection readApiTestViolations, trusted=true
[junit4] 2> 1957410 INFO (qtp33527590-27587) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_43093.solr.core.readApiTestViolations.shard1.replica_n1' (registry
'solr.core.readApiTestViolations.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@b5826a
[junit4] 2> 1957410 INFO (qtp33527590-27587) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1957410 INFO (qtp33527590-27587) [ ] o.a.s.c.SolrCore
[[readApiTestViolations_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/readApiTestViolations_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001/tempDir-001/node2/./readApiTestViolations_shard1_replica_n1/data/]
[junit4] 2> 1957488 INFO (qtp33527590-27579) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1957488 INFO (qtp33527590-27579) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1957489 INFO (qtp14165054-27586) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1957489 INFO (qtp14165054-27586) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1957490 INFO (qtp33527590-27579) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1957490 INFO (qtp33527590-27579) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1957490 INFO (qtp14165054-27586) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1957490 INFO (qtp14165054-27586) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1957491 INFO (qtp33527590-27579) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1ecc6cc[readApiTestViolations_shard1_replica_n5] main]
[junit4] 2> 1957491 INFO (qtp33527590-27579) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1957492 INFO (qtp33527590-27579) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1957492 INFO (qtp14165054-27586) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4745c0[readApiTestViolations_shard1_replica_n4] main]
[junit4] 2> 1957492 INFO (qtp33527590-27579) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1957492 INFO (qtp14165054-27586) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1957492 INFO (qtp33527590-27579) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570715251513491456
[junit4] 2> 1957492 INFO (qtp14165054-27586) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1957493 INFO (qtp14165054-27586) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1957493 INFO (qtp14165054-27586) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570715251514540032
[junit4] 2> 1957494 INFO (searcherExecutor-8584-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n5] Registered new
searcher Searcher@1ecc6cc[readApiTestViolations_shard1_replica_n5]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1957497 INFO (qtp33527590-27587) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1957497 INFO (qtp14165054-27584) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1957497 INFO (qtp33527590-27587) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1957497 INFO (qtp14165054-27584) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1957497 INFO (qtp33527590-27581) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1957497 INFO (qtp33527590-27581) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1957498 INFO (qtp14165054-27584) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1957498 INFO (qtp14165054-27584) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1957498 INFO (qtp33527590-27587) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1957498 INFO (qtp33527590-27587) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1957498 INFO (qtp33527590-27581) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1957498 INFO (qtp33527590-27581) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1957499 INFO (qtp14165054-27584) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@a7fc09[readApiTestViolations_shard1_replica_n6] main]
[junit4] 2> 1957501 INFO (qtp33527590-27581) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7aa232[readApiTestViolations_shard1_replica_n3] main]
[junit4] 2> 1957501 INFO (searcherExecutor-8580-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n4] Registered new
searcher Searcher@4745c0[readApiTestViolations_shard1_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1957502 INFO (qtp14165054-27584) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1957503 INFO (qtp14165054-27584) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1957503 INFO (qtp33527590-27581) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1957503 INFO (qtp33527590-27581) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1957504 INFO (qtp14165054-27584) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1957504 INFO (qtp33527590-27581) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1957504 INFO (searcherExecutor-8581-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n6] Registered new
searcher Searcher@a7fc09[readApiTestViolations_shard1_replica_n6]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1957504 INFO (qtp14165054-27584) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570715251526074368
[junit4] 2> 1957504 INFO (searcherExecutor-8582-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n3] Registered new
searcher Searcher@7aa232[readApiTestViolations_shard1_replica_n3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1957505 INFO (qtp33527590-27587) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1de5263[readApiTestViolations_shard1_replica_n1] main]
[junit4] 2> 1957505 INFO (qtp33527590-27581) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570715251527122944
[junit4] 2> 1957505 INFO (qtp33527590-27579) [ ]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=6 found=2 timeoutin=9999ms
[junit4] 2> 1957505 INFO (qtp33527590-27587) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1957505 INFO (qtp14165054-27578) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1957505 INFO (qtp14165054-27578) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1957505 INFO (qtp33527590-27587) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1957506 INFO (qtp33527590-27587) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1957507 INFO (qtp14165054-27578) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1957507 INFO (qtp14165054-27578) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1957507 INFO (searcherExecutor-8585-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n1] Registered new
searcher Searcher@1de5263[readApiTestViolations_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1957507 INFO (qtp33527590-27587) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570715251529220096
[junit4] 2> 1957507 INFO (qtp14165054-27578) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@11f4449[readApiTestViolations_shard1_replica_n2] main]
[junit4] 2> 1957508 INFO (qtp14165054-27578) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1957508 INFO (qtp14165054-27578) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1957509 INFO (qtp14165054-27578) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1957509 INFO (searcherExecutor-8583-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n2] Registered new
searcher Searcher@11f4449[readApiTestViolations_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1957509 INFO (qtp14165054-27578) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1570715251531317248
[junit4] 2> 1957605 INFO (zkCallback-5883-thread-1) [ ]
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> 1957606 INFO (zkCallback-5884-thread-1) [ ]
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> 1958006 INFO (qtp33527590-27579) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1958006 INFO (qtp33527590-27579) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1958006 INFO (qtp33527590-27579) [ ] o.a.s.c.SyncStrategy
Sync replicas to
http://127.0.0.1:43093/solr/readApiTestViolations_shard1_replica_n5/
[junit4] 2> 1958006 INFO (qtp33527590-27579) [ ] o.a.s.u.PeerSync
PeerSync: core=readApiTestViolations_shard1_replica_n5
url=http://127.0.0.1:43093/solr START
replicas=[http://127.0.0.1:43093/solr/readApiTestViolations_shard1_replica_n3/,
http://127.0.0.1:33405/solr/readApiTestViolations_shard1_replica_n2/,
http://127.0.0.1:33405/solr/readApiTestViolations_shard1_replica_n4/,
http://127.0.0.1:43093/solr/readApiTestViolations_shard1_replica_n1/,
http://127.0.0.1:33405/solr/readApiTestViolations_shard1_replica_n6/]
nUpdates=100
[junit4] 2> 1958007 INFO (qtp33527590-27653) [ ] o.a.s.c.S.Request
[readApiTestViolations_shard1_replica_n3] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1958008 INFO (qtp14165054-27573) [ ] o.a.s.c.S.Request
[readApiTestViolations_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1958008 INFO (qtp14165054-27582) [ ] o.a.s.c.S.Request
[readApiTestViolations_shard1_replica_n4] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1958008 INFO (qtp33527590-27673) [ ] o.a.s.c.S.Request
[readApiTestViolations_shard1_replica_n1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1958008 INFO (qtp33527590-27579) [ ] o.a.s.u.PeerSync
PeerSync: core=readApiTestViolations_shard1_replica_n5
url=http://127.0.0.1:43093/solr DONE. We have no versions. sync failed.
[junit4] 2> 1958009 INFO (qtp14165054-27573) [ ] o.a.s.c.S.Request
[readApiTestViolations_shard1_replica_n6] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1958009 INFO (qtp33527590-27579) [ ] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1958009 INFO (qtp33527590-27579) [ ]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1958009 INFO (qtp33527590-27579) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1958012 INFO (qtp33527590-27579) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:43093/solr/readApiTestViolations_shard1_replica_n5/ shard1
[junit4] 2> 1958113 INFO (zkCallback-5883-thread-1) [ ]
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> 1958113 INFO (zkCallback-5884-thread-1) [ ]
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> 1958162 INFO (qtp33527590-27579) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1958164 INFO (qtp33527590-27579) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n5&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1796
[junit4] 2> 1958265 INFO (zkCallback-5884-thread-1) [ ]
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> 1958265 INFO (zkCallback-5883-thread-1) [ ]
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> 1958505 INFO (qtp14165054-27586) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n4&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2138
[junit4] 2> 1958508 INFO (qtp14165054-27584) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n6&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2142
[junit4] 2> 1958508 INFO (qtp33527590-27581) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n3&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2142
[junit4] 2> 1958510 INFO (qtp33527590-27587) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n1&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2143
[junit4] 2> 1958513 INFO (qtp14165054-27578) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=readApiTestViolations_shard1_replica_n2&action=CREATE&numShards=1&collection=readApiTestViolations&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2146
[junit4] 2> 1958514 INFO (qtp33527590-27575) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 1958613 INFO (zkCallback-5883-thread-1) [ ]
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> 1958613 INFO (zkCallback-5884-thread-1) [ ]
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> 1959514 INFO (qtp33527590-27575) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={pullReplicas=0&replicationFactor=6&maxShardsPerNode=10&name=readApiTestViolations&nrtReplicas=6&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2}
status=0 QTime=3254
[junit4] 2> 1959516 INFO (qtp33527590-27585) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1959516 INFO (qtp33527590-27585) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32943/solr ready
[junit4] 2> 1959518 INFO (qtp33527590-27653) [ ] 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> 1959519 INFO (qtp14165054-27674) [ ] 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> 1959520 INFO (qtp33527590-27585) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/autoscaling/diagnostics
params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1959521 INFO
(TEST-AutoScalingHandlerTest.testReadApi-seed#[DB220AFF163D3283]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testReadApi
[junit4] 2> 1959529 INFO
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[DB220AFF163D3283])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testPolicyAndPreferences
[junit4] 2> 1959532 ERROR (qtp14165054-27574) [ ]
o.a.s.h.RequestHandlerBase java.lang.RuntimeException: cores is only allowed in
'cluster-policy'
[junit4] 2> at
org.apache.solr.cloud.autoscaling.Policy.lambda$new$1(Policy.java:107)
[junit4] 2> at
java.base/java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:176)
[junit4] 2> at
java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
[junit4] 2> at
java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1492)
[junit4] 2> at
java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484)
[junit4] 2> at
java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474)
[junit4] 2> at
java.base/java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:913)
[junit4] 2> at
java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
[junit4] 2> at
java.base/java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:511)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.Policy.lambda$new$2(Policy.java:112)
[junit4] 2> at
java.base/java.util.HashMap.forEach(HashMap.java:1340)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.Policy.<init>(Policy.java:102)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:275)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.zkSetPolicies(AutoScalingHandler.java:224)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleSetPolicies(AutoScalingHandler.java:205)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:104)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
[junit4] 2> at
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:312)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1959532 INFO (qtp14165054-27574) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2}
status=500 QTime=1
[junit4] 2> 1959532 ERROR (qtp14165054-27574) [ ] o.a.s.s.HttpSolrCall
null:java.lang.RuntimeException: cores is only allowed in 'cluster-policy'
[junit4] 2> at
org.apache.solr.cloud.autoscaling.Policy.lambda$new$1(Policy.java:107)
[junit4] 2> at
java.base/java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:176)
[junit4] 2> at
java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
[junit4] 2> at
java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1492)
[junit4] 2> at
java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484)
[junit4] 2> at
java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474)
[junit4] 2> at
java.base/java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:913)
[junit4] 2> at
java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
[junit4] 2> at
java.base/java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:511)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.Policy.lambda$new$2(Policy.java:112)
[junit4] 2> at
java.base/java.util.HashMap.forEach(HashMap.java:1340)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.Policy.<init>(Policy.java:102)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:275)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.zkSetPolicies(AutoScalingHandler.java:224)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleSetPolicies(AutoScalingHandler.java:205)
[junit4] 2> at
org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:104)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
[junit4] 2> at
org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at
org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:312)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:728)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:509)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:395)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 1959532 INFO
(TEST-AutoScalingHandlerTest.testPolicyAndPreferences-seed#[DB220AFF163D3283])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testPolicyAndPreferences
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=AutoScalingHandlerTest -Dtests.method=testPolicyAndPreferences
-Dtests.seed=DB220AFF163D3283 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=to-TO -Dtests.timezone=America/Montserrat -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.01s J2 | AutoScalingHandlerTest.testPolicyAndPreferences
<<<
[junit4] > Throwable #1: java.lang.AssertionError: Adding a policy with
'cores' attribute should not have succeeded.
[junit4] > at
__randomizedtesting.SeedInfo.seed([DB220AFF163D3283:7AEA54D66F7B348D]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.AutoScalingHandlerTest.testPolicyAndPreferences(AutoScalingHandlerTest.java:83)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 1959537 INFO (jetty-closer-5873-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@1213500{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1959537 INFO (jetty-closer-5873-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@958865{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1959540 INFO (jetty-closer-5873-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=24506771
[junit4] 2> 1959540 INFO (jetty-closer-5873-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=23404359
[junit4] 2> 1959541 INFO (jetty-closer-5873-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1959541 INFO (jetty-closer-5873-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1959542 INFO (jetty-closer-5873-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1959542 INFO (jetty-closer-5873-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1959543 INFO (jetty-closer-5873-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1959543 INFO (jetty-closer-5873-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1959543 INFO (jetty-closer-5873-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1959544 INFO (coreCloseExecutor-8610-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n5] CLOSING SolrCore
org.apache.solr.core.SolrCore@1d3fb23
[junit4] 2> 1959544 INFO (coreCloseExecutor-8610-thread-2) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n3] CLOSING SolrCore
org.apache.solr.core.SolrCore@f43c27
[junit4] 2> 1959544 INFO (coreCloseExecutor-8610-thread-3) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@a0282f
[junit4] 2> 1959544 INFO (jetty-closer-5873-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1959544 INFO (coreCloseExecutor-8610-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n3, tag=16006183
[junit4] 2> 1959544 INFO (coreCloseExecutor-8611-thread-1) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n4] CLOSING SolrCore
org.apache.solr.core.SolrCore@19144bf
[junit4] 2> 1959547 INFO (coreCloseExecutor-8611-thread-2) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n6] CLOSING SolrCore
org.apache.solr.core.SolrCore@74ce5c
[junit4] 2> 1959547 INFO (coreCloseExecutor-8610-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n5, tag=30669603
[junit4] 2> 1959547 INFO (coreCloseExecutor-8611-thread-3) [ ]
o.a.s.c.SolrCore [readApiTestViolations_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@18ab485
[junit4] 2> 1959550 INFO (coreCloseExecutor-8610-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=16006183
[junit4] 2> 1959550 INFO (coreCloseExecutor-8611-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n4, tag=26297535
[junit4] 2> 1959552 INFO (coreCloseExecutor-8611-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=26297535
[junit4] 2> 1959552 INFO (coreCloseExecutor-8610-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=30669603
[junit4] 2> 1959552 INFO (coreCloseExecutor-8610-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n1, tag=10496047
[junit4] 2> 1959555 INFO (coreCloseExecutor-8611-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n2, tag=25867397
[junit4] 2> 1959572 INFO (coreCloseExecutor-8610-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=10496047
[junit4] 2> 1959573 INFO (zkCallback-5884-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1959574 INFO (coreCloseExecutor-8611-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.readApiTestViolations.shard1.replica_n6, tag=7655004
[junit4] 2> 1959574 INFO (jetty-closer-5873-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@462c67{/solr,null,UNAVAILABLE}
[junit4] 2> 1959576 INFO (coreCloseExecutor-8611-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=25867397
[junit4] 2> 1959577 INFO (coreCloseExecutor-8611-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.readApiTestViolations.shard1.leader, tag=7655004
[junit4] 2> 1959577 INFO (jetty-closer-5873-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98169703120764934-127.0.0.1:33405_solr-n_0000000000) closing
[junit4] 2> 1959577 INFO
(OverseerStateUpdate-98169703120764934-127.0.0.1:33405_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:33405_solr
[junit4] 2> 1959581 INFO (jetty-closer-5873-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6748e7{/solr,null,UNAVAILABLE}
[junit4] 2> 1959581 ERROR
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-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> 1959581 INFO
(SUITE-AutoScalingHandlerTest-seed#[DB220AFF163D3283]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:32943 32943
[junit4] 2> 1959697 INFO (Thread-3589) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:32943 32943
[junit4] 2> 1959697 WARN (Thread-3589) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 2
/solr/collections/readApiTestViolations/leader_elect/shard1/election/98169703120764934-core_node5-n_0000000002
[junit4] 2> 2
/solr/collections/readApiTestViolations/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.AutoScalingHandlerTest_DB220AFF163D3283-001
[junit4] 2> Jun 20, 2017 9:26:35 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene70,
sim=RandomSimilarity(queryNorm=true): {}, locale=to-TO,
timezone=America/Montserrat
[junit4] 2> NOTE: Linux 4.10.0-21-generic i386/Oracle Corporation 9-ea
(32-bit)/cpus=8,threads=1,free=151310248,total=411041792
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedSchemaAPI,
TestPhraseSuggestions, TestPayloadCheckQParserPlugin, ResponseHeaderTest,
ForceLeaderTest, OverseerTaskQueueTest, V2StandaloneTest,
TestDocBasedVersionConstraints, TestClassNameShortening, TestDocSet,
FullSolrCloudDistribCmdsTest, TestFreeTextSuggestions, TestCoreDiscovery,
TestGroupingSearch, QueryResultKeyTest, StressHdfsTest,
TestManagedResourceStorage, LeaderFailoverAfterPartitionTest,
TestPHPSerializedResponseWriter, TestExtendedDismaxParser, TestPolicyCloud,
HLLSerializationTest, TestRecoveryHdfs, RecoveryZkTest,
DistributedExpandComponentTest, PropertiesRequestHandlerTest,
HardAutoCommitTest, HdfsChaosMonkeyNothingIsSafeTest,
XmlUpdateRequestHandlerTest, ClassificationUpdateProcessorTest, TestLockTree,
TestConfigSetImmutable, TestMaxTokenLenTokenizer, TestZkChroot,
TestNumericTerms32, PluginInfoTest, TestUtils,
SuggestComponentContextFilterQueryTest, PrimitiveFieldTypeTest, TestSQLHandler,
TestImplicitCoreProperties, TestMiniSolrCloudClusterSSL,
TestSolrCloudSnapshots, DebugComponentTest, TestNRTOpen,
TestLMDirichletSimilarityFactory, TestBinaryField, DirectUpdateHandlerTest,
SaslZkACLProviderTest, TestCollationField, TestFastOutputStream,
BlockJoinFacetSimpleTest, TestCloudPivotFacet, TestRequestStatusCollectionAPI,
TestDistribIDF, TestSchemaManager, AutoCommitTest, SolrTestCaseJ4Test,
TestFieldCacheVsDocValues, TestGraphTermsQParserPlugin,
TestPerFieldSimilarityWithDefaultOverride,
PreAnalyzedFieldManagedSchemaCloudTest, HdfsRestartWhileUpdatingTest,
OutputWriterTest, TestManagedSynonymFilterFactory, BitVectorTest,
TestRuleBasedAuthorizationPlugin, BasicDistributedZk2Test,
SolrCloudReportersTest, TestJsonRequest,
HdfsTlogReplayBufferedWhileIndexingTest, TestSolrCloudWithKerberosAlt,
CurrencyFieldXmlFileTest, LeaderInitiatedRecoveryOnShardRestartTest,
FieldAnalysisRequestHandlerTest, EchoParamsTest,
SharedFSAutoReplicaFailoverUtilsTest, TestCollapseQParserPlugin,
ShowFileRequestHandlerTest, ExternalFileFieldSortTest,
TestClassicSimilarityFactory, MetricUtilsTest, HighlighterConfigTest,
SolrCloudExampleTest, DocValuesMissingTest, TestJettySolrRunner,
TestCSVResponseWriter, TestLegacyNumericRangeQueryBuilder,
TestInPlaceUpdatesDistrib, TestCloudManagedSchema, TestWriterPerf,
TestTolerantSearch, TestShortCircuitedRequests, SimpleFacetsTest,
MoveReplicaTest, TestSolrQueryResponse, TestNestedDocsSort,
TestSerializedLuceneMatchVersion, TestXmlQParserPlugin,
TestSolrDeletionPolicy1, BadIndexSchemaTest, TestIBSimilarityFactory,
BJQParserTest, TestPushWriter, RemoteQueryErrorTest, SolrIndexConfigTest,
CoreAdminHandlerTest, TestDeleteCollectionOnDownNodes, TestMacros,
DistribCursorPagingTest, TestIndexingPerformance, SuggesterTSTTest,
DeleteInactiveReplicaTest, TestTolerantUpdateProcessorCloud, TestRecovery,
TestLRUStatsCache, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest,
SyncSliceTest, BasicZkTest, TestRandomFaceting, LeaderElectionTest,
ShardRoutingCustomTest, TermVectorComponentDistributedTest, TestRealTimeGet,
TestReload, TestSolr4Spatial, StatsComponentTest, TestFiltering,
DistributedQueryElevationComponentTest, SimplePostToolTest,
SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterFSTTest,
SolrRequestParserTest, DocValuesTest, SuggesterTest, TestStressLucene,
TestTrie, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest,
SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields,
FieldMutatingUpdateProcessorTest, TestWordDelimiterFilterFactory,
QueryEqualityTest, DirectUpdateHandlerOptimizeTest,
StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest,
TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest,
XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest,
TestSurroundQueryParser, LukeRequestHandlerTest, DirectSolrSpellCheckerTest,
TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest,
TestQueryTypes, TermsComponentTest, DocumentBuilderTest,
TermVectorComponentTest, RegexBoostProcessorTest, IndexSchemaTest,
TestAnalyzedSuggestions, PingRequestHandlerTest, TestQuerySenderListener,
UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig,
TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest,
TestMergePolicyConfig, TestSolrDeletionPolicy2, MinimalSchemaTest,
TestElisionMultitermQuery, TestConfig, DirectSolrConnectionTest,
NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestCharFilters,
SynonymTokenizerTest, TestXIncludeConfig, TestPerFieldSimilarity,
TestBM25SimilarityFactory, ResourceLoaderTest,
OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, TestFastLRUCache,
ChaosMonkeyNothingIsSafeTest, TestHighlightDedupGrouping,
TestEmbeddedSolrServerConstructors, ConnectionReuseTest, AssignTest,
BaseCdcrDistributedZkTest, CdcrBootstrapTest, CleanupOldIndexTest,
CloudExitableDirectoryReaderTest, CollectionReloadTest,
CollectionStateFormat2Test, CollectionsAPISolrJTest, DeleteShardTest,
DistributedQueueTest, LeaderInitiatedRecoveryOnCommitTest,
MultiThreadedOCPTest, ReplicationFactorTest, RestartWhileUpdatingTest,
SharedFSAutoReplicaFailoverTest, SimpleCollectionCreateDeleteTest,
SolrCLIZkUtilsTest, TestAuthenticationFramework, TestConfigSetsAPI,
TestMiniSolrCloudCluster, TestRebalanceLeaders, TestStressLiveNodes,
TestTlogReplica, AutoScalingHandlerTest]
[junit4] Completed [551/725 (1!)] on J2 in 3.97s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 36884 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
