Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22517/
Java: 64bit/jdk-10 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeLost
Error Message:
org.apache.solr.common.SolrException:
Stack Trace:
org.apache.solr.common.SolrException: org.apache.solr.common.SolrException:
at
__randomizedtesting.SeedInfo.seed([E831FB6565CC71BB:5724359BE626143D]:0)
at
org.apache.solr.common.cloud.rule.ImplicitSnitch.getTags(ImplicitSnitch.java:78)
at
org.apache.solr.client.solrj.impl.SolrClientNodeStateProvider.fetchTagValues(SolrClientNodeStateProvider.java:130)
at
org.apache.solr.client.solrj.impl.SolrClientNodeStateProvider.getNodeValues(SolrClientNodeStateProvider.java:122)
at
org.apache.solr.cloud.autoscaling.ComputePlanActionTest.printState(ComputePlanActionTest.java:151)
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:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
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
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)
Caused by: org.apache.solr.common.SolrException:
at
org.apache.solr.client.solrj.impl.SolrClientNodeStateProvider$AutoScalingSnitch.getRemoteInfo(SolrClientNodeStateProvider.java:291)
at
org.apache.solr.common.cloud.rule.ImplicitSnitch.getTags(ImplicitSnitch.java:76)
... 39 more
Caused by: org.apache.solr.client.solrj.SolrServerException: Server refused
connection at: https://127.0.0.1:43199/solr
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:650)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at
org.apache.solr.client.solrj.impl.SolrClientNodeStateProvider$ClientSnitchCtx.invoke(SolrClientNodeStateProvider.java:333)
at
org.apache.solr.client.solrj.impl.SolrClientNodeStateProvider$AutoScalingSnitch.getRemoteInfo(SolrClientNodeStateProvider.java:264)
... 40 more
Caused by: org.apache.http.conn.HttpHostConnectException: Connect to
127.0.0.1:43199 [/127.0.0.1] failed: Connection refused (Connection refused)
at
org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:159)
at
org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:359)
at
org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:381)
at
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:237)
at
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
at
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
... 45 more
Caused by: java.net.ConnectException: Connection refused (Connection refused)
at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
at
java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:400)
at
java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:243)
at
java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:225)
at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:402)
at java.base/java.net.Socket.connect(Socket.java:591)
at
org.apache.http.conn.ssl.SSLConnectionSocketFactory.connectSocket(SSLConnectionSocketFactory.java:339)
at
org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:142)
... 55 more
Build Log:
[...truncated 14047 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/init-core-data-001
[junit4] 2> 1549597 INFO
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001
[junit4] 2> 1549597 INFO
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1549597 INFO (Thread-5185) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1549597 INFO (Thread-5185) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1549599 ERROR (Thread-5185) [ ] 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> 1549697 INFO
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:36135
[junit4] 2> 1549699 INFO (zkConnectionManagerCallback-4623-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549701 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10+46
[junit4] 2> 1549702 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1549702 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1549702 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1549702 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5a3290d3{/solr,null,AVAILABLE}
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@41836279{SSL,[ssl,
http/1.1]}{127.0.0.1:41379}
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.e.j.s.Server Started @1549737ms
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41379}
[junit4] 2> 1549703 ERROR (jetty-launcher-4620-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1549703 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-07-24T17:23:49.762592Z
[junit4] 2> 1549704 INFO (zkConnectionManagerCallback-4625-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549704 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1549765 INFO (jetty-launcher-4620-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36135/solr
[junit4] 2> 1549766 INFO (zkConnectionManagerCallback-4629-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549767 INFO (zkConnectionManagerCallback-4631-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549803 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:41379_solr
[junit4] 2> 1549804 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.c.Overseer Overseer
(id=72183567762980867-127.0.0.1:41379_solr-n_0000000000) starting
[junit4] 2> 1549807 INFO (zkConnectionManagerCallback-4638-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549808 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36135/solr ready
[junit4] 2> 1549809 INFO
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41379_solr
[junit4] 2> 1549809 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41379_solr
[junit4] 2> 1549809 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 1549809 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode
version 1
[junit4] 2> 1549809 INFO (zkCallback-4630-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1549809 INFO (zkCallback-4637-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1549810 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion -1
[junit4] 2> 1549810 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 1549811 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:41379_solr]
[junit4] 2> 1549811 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1549811 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 1549811 DEBUG (ScheduledTrigger-7837-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 1
[junit4] 2> 1549817 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1549834 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41379.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1549843 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41379.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1549843 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41379.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1549844 INFO (jetty-launcher-4620-thread-1)
[n:127.0.0.1:41379_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001/node1/.
[junit4] 2> 1549857 INFO (zkConnectionManagerCallback-4641-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549859 INFO (zkConnectionManagerCallback-4646-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1549860 INFO
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1549860 INFO
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36135/solr ready
[junit4] 2> 1549867 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 1549867 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 1549868 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1549869 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1549869 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1549869 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 1549894 INFO (qtp388496182-19596) [n:127.0.0.1:41379_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> 1549895 DEBUG (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1549896 INFO (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 1549896 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 1549896 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 1549896 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1549900 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1549900 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 1549902 INFO (qtp388496182-19638) [n:127.0.0.1:41379_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> 1549902 DEBUG (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1549902 INFO (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1549902 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
4
[junit4] 2> 1549902 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 4
[junit4] 2> 1549903 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1549903 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1549903 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4,
lastZnodeVersion 4
[junit4] 2> 1549905 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1549905 DEBUG (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1549905 INFO (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1549906 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
5
[junit4] 2> 1549906 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 5
[junit4] 2> 1549907 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger -
Initial livenodes: [127.0.0.1:41379_solr]
[junit4] 2> 1549907 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger
instantiated with properties: {event=nodeAdded, waitFor=1,
actions=[{name=compute_plan, class=solr.ComputePlanAction}, {name=test,
class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}],
enabled=true}
[junit4] 2> 1549907 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1549907 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5,
lastZnodeVersion 5
[junit4] 2> 1549907 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1549907 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 1549907 INFO (qtp388496182-19638) [n:127.0.0.1:41379_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> 1549908 DEBUG (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1549908 INFO (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling
params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1549908 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
6
[junit4] 2> 1549908 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 6
[junit4] 2> 1549908 INFO (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1549908 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1549908 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6,
lastZnodeVersion 6
[junit4] 2> 1549909 INFO (OverseerThreadFactory-7839-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Create collection testNodeAdded
[junit4] 2> 1549910 WARN (OverseerThreadFactory-7839-thread-1) [ ]
o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 2 on collection
testNodeAdded is higher than the number of Solr instances currently live or
live and part of your createNodeSet(1). It's unusual to run two replica of the
same slice on the same Solr-instance.
[junit4] 2> 1549912 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1549914 INFO (OverseerThreadFactory-7839-thread-1) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1922206815
sessionWrapper.createTime 1922206813123480, this.sessionWrapper.createTime
1922206813123480
[junit4] 2> 1549916 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testNodeAdded",
[junit4] 2> "fromApi":"true",
[junit4] 2> "collection.configName":"conf",
[junit4] 2> "numShards":"1",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1549916 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded
[junit4] 2> 1549917 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to create_collection
/collections/testNodeAdded/state.json
[junit4] 2> 1550018 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1550018 INFO
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1550018 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1550018 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1550019 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1550019 INFO
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1550019 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1550019 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 1550119 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 0
[junit4] 2> 1550222 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1550222 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create
command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1550222 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault
Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1550223 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1550223 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1550223 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Will update state for replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:41379/solr","node_name":"127.0.0.1:41379_solr","state":"down","type":"NRT","force_set_state":"false"}
[junit4] 2> 1550223 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "state":"down",
[junit4] 2> "node_name":null,
[junit4] 2> "type":"NRT"}}}}}
[junit4] 2> 1550224 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1550224 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1550224 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Will update state for replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:41379/solr","node_name":"127.0.0.1:41379_solr","state":"down","type":"NRT","force_set_state":"false"}
[junit4] 2> 1550224 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/1)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1550325 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 1
[junit4] 2> 1550907 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1550907 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 1551227 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1551227 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1551231 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n2] Schema name=minimal
[junit4] 2> 1551231 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.IndexSchema [testNodeAdded_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1551232 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1551232 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n2' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 1551232 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1551232 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'testNodeAdded_shard1_replica_n1' using
configuration from collection testNodeAdded, trusted=true
[junit4] 2> 1551233 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41379.solr.core.testNodeAdded.shard1.replica_n2' (registry
'solr.core.testNodeAdded.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1551233 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41379.solr.core.testNodeAdded.shard1.replica_n1' (registry
'solr.core.testNodeAdded.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1551233 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1551233 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1551233 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/]
[junit4] 2> 1551233 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.SolrCore [[testNodeAdded_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/]
[junit4] 2> 1551298 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1551298 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1551299 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1551300 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1551301 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@e3efa14[testNodeAdded_shard1_replica_n2] main]
[junit4] 2> 1551302 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1551302 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1551302 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1551303 INFO
(searcherExecutor-7844-thread-1-processing-n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2 c:testNodeAdded s:shard1 r:core_node4)
[n:127.0.0.1:41379_solr c:testNodeAdded s:shard1 r:core_node4
x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n2] Registered new searcher
Searcher@e3efa14[testNodeAdded_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1551303 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1606893469813440512
[junit4] 2> 1551307 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/testNodeAdded/terms/shard1 to Terms{values={core_node4=0},
version=0}
[junit4] 2> 1551309 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded"} current state version: 0
[junit4] 2> 1551309 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1551309 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1551309 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1551310 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1551310 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1551311 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@39ad4847[testNodeAdded_shard1_replica_n1] main]
[junit4] 2> 1551312 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1551312 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1551313 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1551313 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1606893469823926272
[junit4] 2> 1551313 INFO
(searcherExecutor-7845-thread-1-processing-n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1 c:testNodeAdded s:shard1 r:core_node3)
[n:127.0.0.1:41379_solr c:testNodeAdded s:shard1 r:core_node3
x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n1] Registered new searcher
Searcher@39ad4847[testNodeAdded_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1551315 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.ZkShardTerms Successful update of terms at
/collections/testNodeAdded/terms/shard1 to Terms{values={core_node3=0,
core_node4=0}, version=1}
[junit4] 2> 1551409 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 2
[junit4] 2> 1551810 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1551810 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1551810 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:41379/solr/testNodeAdded_shard1_replica_n2/
[junit4] 2> 1551810 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n2
url=https://127.0.0.1:41379/solr START
replicas=[https://127.0.0.1:41379/solr/testNodeAdded_shard1_replica_n1/]
nUpdates=100
[junit4] 2> 1551810 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.u.PeerSync PeerSync: core=testNodeAdded_shard1_replica_n2
url=https://127.0.0.1:41379/solr DONE. We have no versions. sync failed.
[junit4] 2> 1551813 INFO (qtp388496182-19642) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.c.S.Request [testNodeAdded_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> 1551813 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 1551813 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
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> 1551813 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1551815 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:41379/solr/testNodeAdded_shard1_replica_n2/ shard1
[junit4] 2> 1551815 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 1551908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1551908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 1551920 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 3
[junit4] 2> 1551970 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1551976 INFO (qtp388496182-19643) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n2&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1754
[junit4] 2> 1551976 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1551976 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1551976 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Will update state for replica:
core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"https://127.0.0.1:41379/solr","node_name":"127.0.0.1:41379_solr","state":"active","type":"NRT","force_set_state":"false","leader":"true"}
[junit4] 2> 1551976 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/4)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1552077 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 4
[junit4] 2> 1552077 INFO (zkCallback-4630-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1552317 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1552317 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1552317 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Will update state for replica:
core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"https://127.0.0.1:41379/solr","node_name":"127.0.0.1:41379_solr","state":"active","type":"NRT","force_set_state":"false"}
[junit4] 2> 1552317 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ReplicaMutator Collection is now:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/5)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1552376 INFO (qtp388496182-19596) [n:127.0.0.1:41379_solr
c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testNodeAdded_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAdded&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2154
[junit4] 2> 1552377 INFO (qtp388496182-19594) [n:127.0.0.1:41379_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> 1552417 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.ZkStateWriter going to update_collection
/collections/testNodeAdded/state.json version: 5
[junit4] 2> 1552417 INFO (zkCallback-4630-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/testNodeAdded/state.json] for collection [testNodeAdded] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1552908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1552908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 1
[junit4] 2> 1553377 INFO (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={collection.configName=conf&name=testNodeAdded&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=3469
[junit4] 2> 1553382 INFO (qtp388496182-19638) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 1553384 INFO (qtp388496182-19596) [n:127.0.0.1:41379_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> 1553386 DEBUG (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1553386 INFO (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1553386 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
7
[junit4] 2> 1553386 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 7
[junit4] 2> 1553386 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1553387 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7,
lastZnodeVersion 7
[junit4] 2> 1553387 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git:
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10+46
[junit4] 2> 1553388 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1553388 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1553388 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1553388 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6a3ddd71{/solr,null,AVAILABLE}
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@41b3a6a4{SSL,[ssl,
http/1.1]}{127.0.0.1:44723}
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.Server Started @1553423ms
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44723}
[junit4] 2> 1553389 ERROR
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
8.0.0
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1553389 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-07-24T17:23:53.448640Z
[junit4] 2> 1553390 INFO (zkConnectionManagerCallback-4650-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1553390 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1553441 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36135/solr
[junit4] 2> 1553442 INFO (zkConnectionManagerCallback-4654-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1553443 INFO (zkConnectionManagerCallback-4656-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1553445 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1553447 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 1553447 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44723_solr
[junit4] 2> 1553447 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:44723_solr"} current state version: 0
[junit4] 2> 1553447 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:44723_solr
[junit4] 2> 1553448 INFO (zkCallback-4637-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1553448 INFO (zkCallback-4630-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1553448 INFO (zkCallback-4645-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1553448 INFO (zkCallback-4645-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1553448 INFO (zkCallback-4655-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1553457 INFO (zkConnectionManagerCallback-4663-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1553458 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 1553458 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster
at 127.0.0.1:36135/solr ready
[junit4] 2> 1553458 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 1553469 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44723.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1553475 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44723.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1553475 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44723.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@14d07bae
[junit4] 2> 1553476 INFO
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB])
[n:127.0.0.1:44723_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001/tempDir-001/node2/.
[junit4] 2> 1553908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1553908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 1553908 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:44723_solr at time
1922210808794517
[junit4] 2> 1553913 INFO
(OverseerCollectionConfigSetProcessor-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] 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> 1554909 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 1554909 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 1554909 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing
registered processor for nodes: [127.0.0.1:44723_solr] added at times
[1922210808794517], now=1922211809537388
[junit4] 2> 1554909 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"6d43d94710d95Tav18eej5ztvygb297tzl1xmo7",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1922210808794517,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1922210808794517],
[junit4] 2> "nodeNames":["127.0.0.1:44723_solr"]}}
[junit4] 2> 1554909 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger]
[junit4] 2> 1554910 INFO (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling
logging.
[junit4] 2> 1554910 DEBUG (ScheduledTrigger-7837-thread-2) [ ]
o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - removing
marker path: /autoscaling/nodeAdded/127.0.0.1:44723_solr
[junit4] 2> 1554910 DEBUG (AutoscalingActionExecutor-7838-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"6d43d94710d95Tav18eej5ztvygb297tzl1xmo7",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1922210808794517,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1922210808794517],
[junit4] 2> "_enqueue_time_":1922211809971726,
[junit4] 2> "nodeNames":["127.0.0.1:44723_solr"]}}
[junit4] 2> 1554910 DEBUG (AutoscalingActionExecutor-7838-thread-1) [ ]
o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"6d43d94710d95Tav18eej5ztvygb297tzl1xmo7",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1922210808794517,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1922210808794517],
[junit4] 2> "_enqueue_time_":1922211809971726,
[junit4] 2> "nodeNames":["127.0.0.1:44723_solr"]}} with context
properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 1554912 INFO (qtp388496182-19638) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={wt=javabin&version=2&key=solr.core.testNodeAdded.shard1.replica_n1:INDEX.sizeInBytes&key=solr.core.testNodeAdded.shard1.replica_n2:INDEX.sizeInBytes}
status=0 QTime=0
[junit4] 2> 1554913 INFO (qtp388496182-19595) [n:127.0.0.1:41379_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1554927 INFO (qtp1395505336-19656) [n:127.0.0.1:44723_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics
params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core}
status=0 QTime=0
[junit4] 2> 1554928 INFO (AutoscalingActionExecutor-7838-thread-1) [ ]
o.a.s.c.a.ComputePlanAction Computed Plan:
action=MOVEREPLICA&collection=testNodeAdded&targetNode=127.0.0.1:44723_solr&inPlaceMove=true&replica=core_node3
[junit4] 2> 1554928 INFO (AutoscalingActionExecutor-7838-thread-1) [ ]
o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1922211828
sessionWrapper.createTime 1922211827818397, this.sessionWrapper.createTime
1922211827818397
[junit4] 2> 1554928 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.a.ComputePlanActionTest -------------_ FINAL STATE --------------
[junit4] 2> 1554929 DEBUG (AutoscalingActionExecutor-7838-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 5000ms
[junit4] 2> 1554929 DEBUG (AutoscalingActionExecutor-7838-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers -- processing took 18 ms for event
id=6d43d94710d95Tav18eej5ztvygb297tzl1xmo7
[junit4] 2> 1554930 INFO (qtp388496182-19638) [n:127.0.0.1:41379_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> 1554930 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:41379_solr
[junit4] 2> {
[junit4] 2> "node":"127.0.0.1:41379_solr",
[junit4] 2> "cores":2,
[junit4] 2> "port":"41379",
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"127",
[junit4] 2> "freedisk":195.66571044921875}
[junit4] 2> 1554931 INFO (qtp1395505336-19657) [n:127.0.0.1:44723_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> 1554931 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:44723_solr
[junit4] 2> {
[junit4] 2> "node":"127.0.0.1:44723_solr",
[junit4] 2> "cores":0,
[junit4] 2> "port":"44723",
[junit4] 2> "host":"127.0.0.1",
[junit4] 2> "ip_1":"1",
[junit4] 2> "ip_3":"0",
[junit4] 2> "ip_2":"0",
[junit4] 2> "ip_4":"127",
[junit4] 2> "freedisk":195.66571044921875}
[junit4] 2> 1554931 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.a.ComputePlanActionTest * Live nodes: [127.0.0.1:41379_solr,
127.0.0.1:44723_solr]
[junit4] 2> 1554932 DEBUG
(TEST-ComputePlanActionTest.testNodeAdded-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.a.ComputePlanActionTest * Collection testNodeAdded state:
DocCollection(testNodeAdded//collections/testNodeAdded/state.json/6)={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testNodeAdded_shard1_replica_n2",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}},
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0"}
[junit4] 2> 1554944 DEBUG (zkCallback-4637-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
8
[junit4] 2> 1554944 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 8
[junit4] 2> 1554945 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@41b3a6a4{SSL,[ssl,
http/1.1]}{127.0.0.1:0}
[junit4] 2> 1554945 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 1554945 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 1554945 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1403872
[junit4] 2> 1554945 DEBUG
(OverseerAutoScalingTriggerThread-72183567762980867-127.0.0.1:41379_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8,
lastZnodeVersion 8
[junit4] 2> 1554945 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 1554945 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3f29d0cb: rootName =
solr_44723, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@6095e259
[junit4] 2> 1554954 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 1554954 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@2fee0280: rootName =
solr_44723, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@500c0007
[junit4] 2> 1554960 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 1554960 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3a5ca0c0: rootName =
solr_44723, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@1dd42ae2
[junit4] 2> 1554961 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:44723_solr
[junit4] 2> 1554962 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 1554962 INFO (zkCallback-4637-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1554962 INFO (zkCallback-4655-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1554962 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:44723_solr"} current state version: 0
[junit4] 2> 1554962 INFO (zkCallback-4662-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1554962 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:44723_solr
[junit4] 2> 1554962 INFO (zkCallback-4645-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1554962 INFO (zkCallback-4645-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1554962 INFO (zkCallback-4630-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1554963 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6a3ddd71{/solr,null,UNAVAILABLE}
[junit4] 2> 1554963 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1554963 INFO
(TEST-ComputePlanActionTest.testNodeLost-seed#[E831FB6565CC71BB]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1554964 INFO (qtp388496182-19594) [n:127.0.0.1:41379_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testNodeAdded&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1554965 INFO (OverseerThreadFactory-7839-thread-2) [ ]
o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true,
asyncId=null
[junit4] 2> 1554966 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.testNodeAdded.shard1.replica_n1, tag=null
[junit4] 2> 1554966 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@29e9be64: rootName =
solr_41379, domain = solr.core.testNodeAdded.shard1.replica_n1, service url =
null, agent id = null] for registry solr.core.testNodeAdded.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@59b2e93
[junit4] 2> 1554973 INFO (qtp388496182-19591) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.testNodeAdded.shard1.replica_n2, tag=null
[junit4] 2> 1554973 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@3c5b20c2
[junit4] 2> 1554973 INFO (qtp388496182-19591) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3dcc0e2e: rootName =
solr_41379, domain = solr.core.testNodeAdded.shard1.replica_n2, service url =
null, agent id = null] for registry solr.core.testNodeAdded.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@8ef4ed4
[junit4] 2> 1554986 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.testNodeAdded.shard1.replica_n1, tag=3c5b20c2
[junit4] 2> 1554986 INFO (qtp388496182-19591) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore
[testNodeAdded_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@4908709e
[junit4] 2> 1554986 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.testNodeAdded.shard1.leader, tag=3c5b20c2
[junit4] 2> 1554986 INFO (qtp388496182-19591) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.testNodeAdded.shard1.replica_n2, tag=4908709e
[junit4] 2> 1554986 INFO (qtp388496182-19591) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.testNodeAdded.shard1.leader, tag=4908709e
[junit4] 2> 1554988 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/testNodeAdded/terms/shard1 to
Terms{values={core_node4=0}, version=2}
[junit4] 2> 1554988 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase No
version found for ephemeral leader parent node, won't remove previous leader
registration.
[junit4] 2> 1554989 INFO (qtp388496182-19590) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null
path=/admin/cores
params={deleteInstanceDir=true&deleteMetricsHistory=true&core=testNodeAdded_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=23
[junit4] 2> 1554989 DEBUG
(OverseerStateUpdate-72183567762980867-127.0.0.1:41379_solr-n_0000000000) [
] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"deletecore",
[junit4] 2> "core":"testNodeAdded_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:41379_solr",
[junit4] 2> "collection":"testNodeAdded",
[junit4] 2> "base_url":"https://127.0.0.1:41379/solr";,
[junit4] 2> "core_node_name":"core_node3"} current state version: 0
[junit4] 2> 1554989 INFO (qtp388496182-19591) [n:127.0.0.1:41379_solr
x:testNodeAdded_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of
terms at /collections/testNodeAdded/term
[...truncated too long message...]
gers Shutting down action executor now
[junit4] 2> 1589222 DEBUG (jetty-closer-4621-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 1589223 DEBUG (jetty-closer-4621-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 1589223 DEBUG (jetty-closer-4621-thread-1) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1589223 DEBUG (jetty-closer-4621-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1589224 INFO (jetty-closer-4621-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3b17d3bd{/solr,null,UNAVAILABLE}
[junit4] 2> 1589224 INFO (jetty-closer-4621-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1589226 ERROR
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-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> 1589226 INFO
(SUITE-ComputePlanActionTest-seed#[E831FB6565CC71BB]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:36135 36135
[junit4] 2> 1591509 INFO (Thread-5185) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:36135 36135
[junit4] 2> 1591510 WARN (Thread-5185) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/aliases.json
[junit4] 2> 11 /solr/configs/conf
[junit4] 2> 10
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard2
[junit4] 2> 10
/solr/collections/testNodeWithMultipleReplicasLost/terms/shard1
[junit4] 2> 10 /solr/security.json
[junit4] 2> 6 /solr/collections/testNodeLost/terms/shard1
[junit4] 2> 6 /solr/collections/testNodeAdded/terms/shard1
[junit4] 2> 6 /solr/collections/testSelected2/terms/shard1
[junit4] 2> 6 /solr/collections/testSelected1/terms/shard1
[junit4] 2> 6 /solr/collections/testSelected3/terms/shard2
[junit4] 2> 6 /solr/collections/testSelected3/terms/shard1
[junit4] 2> 5 /solr/collections/testSelected1/terms/shard2
[junit4] 2> 5 /solr/collections/testSelected2/terms/shard2
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 25 /solr/clusterprops.json
[junit4] 2> 25 /solr/clusterstate.json
[junit4] 2> 10 /solr/aliases.json
[junit4] 2> 8
/solr/collections/testNodeWithMultipleReplicasLost/state.json
[junit4] 2> 6
/solr/overseer_elect/election/72183567762980867-127.0.0.1:41379_solr-n_0000000000
[junit4] 2> 5 /solr/collections/testSelected1/state.json
[junit4] 2> 5 /solr/collections/testSelected2/state.json
[junit4] 2> 5 /solr/collections/testSelected3/state.json
[junit4] 2> 3 /solr/collections/testNodeAdded/state.json
[junit4] 2> 3 /solr/collections/testNodeLost/state.json
[junit4] 2> 2
/solr/overseer_elect/election/72183567762980887-127.0.0.1:39017_solr-n_0000000004
[junit4] 2> 2
/solr/collections/testNodeWithMultipleReplicasLost/leader_elect/shard1/election/72183567762980908-core_node3-n_0000000000
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2> 2
/solr/overseer_elect/election/72183567762980900-127.0.0.1:41213_solr-n_0000000007
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 69 /solr/live_nodes
[junit4] 2> 58 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/autoscaling/events/node_lost_trigger
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E831FB6565CC71BB-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=522, maxMBSortInHeap=7.852290877354115,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6face17),
locale=si-LK, timezone=America/Cayman
[junit4] 2> NOTE: Linux 4.15.0-24-generic amd64/Oracle Corporation 10
(64-bit)/cpus=8,threads=1,free=175492488,total=536870912
[junit4] 2> NOTE: All tests run in this JVM:
[CloudExitableDirectoryReaderTest, SpatialFilterTest, ZkShardTermsTest,
TestZkChroot, SimpleMLTQParserTest, HdfsChaosMonkeySafeLeaderTest,
TestLazyCores, SpatialHeatmapFacetsTest, TokenizerChainTest,
ChaosMonkeyNothingIsSafeTest, TestNumericTerms64, DocValuesMultiTest,
DistributedFacetPivotSmallTest, LoggingHandlerTest, ReplicaListTransformerTest,
TestSolrCloudWithHadoopAuthPlugin, TestQuerySenderListener,
ChaosMonkeySafeLeaderWithPullReplicasTest, TestExactSharedStatsCache,
TestShardHandlerFactory, ConcurrentCreateRoutedAliasTest,
TestFieldCacheWithThreads, TestStressLucene, TestConfigSets,
TestExtendedDismaxParser, TestSolrQueryParser, ScriptEngineTest,
RestoreTriggerStateTest, TestPartialUpdateDeduplication,
ChangedSchemaMergeTest, TestConfigOverlay, TestSafeXMLParsing,
TestSolrDeletionPolicy1, CollectionReloadTest,
TestLMDirichletSimilarityFactory, TestRawTransformer,
TestUnInvertedFieldException, TestInitQParser, TestFieldResource,
TestClusterProperties, OverseerTest, ReturnFieldsTest, SecurityConfHandlerTest,
DirectSolrSpellCheckerTest, SampleTest, MoveReplicaHDFSTest,
TestRandomRequestDistribution, OverseerRolesTest, UpdateParamsTest,
DefaultValueUpdateProcessorTest, MetricsHandlerTest, SystemLogListenerTest,
MetricTriggerTest, TestDistributedMissingSort, ConfigureRecoveryStrategyTest,
DistanceFunctionTest, DistributedVersionInfoTest, HdfsBasicDistributedZk2Test,
TestFieldCacheSort, TestManagedStopFilterFactory, EchoParamsTest,
DistributedSpellCheckComponentTest, TestRecoveryHdfs,
XmlUpdateRequestHandlerTest, ShardRoutingCustomTest,
TestSha256AuthenticationProvider, DocumentAnalysisRequestHandlerTest,
UtilsToolTest, SolrRrdBackendFactoryTest, SubstringBytesRefFilterTest,
ShufflingReplicaListTransformerTest, TestLeaderElectionZkExpiry,
TestCloudSchemaless, TestSchemaVersionResource, URLClassifyProcessorTest,
HdfsThreadLeakTest, MigrateRouteKeyTest,
OverriddenZkACLAndCredentialsProvidersTest, TestTolerantUpdateProcessorCloud,
TestMacros, RequestHandlersTest, AnalyticsMergeStrategyTest, ReplaceNodeTest,
TestInfoStreamLogging, TestUpdate, TestUseDocValuesAsStored,
TestCloudJSONFacetJoinDomain, HdfsCollectionsAPIDistributedZkTest,
TestIntervalFaceting, TestSSLRandomization, TestSolrXml,
PreAnalyzedFieldManagedSchemaCloudTest, FastVectorHighlighterTest,
TestFilteredDocIdSet, CdcrRequestHandlerTest, TestRestManager,
TestNodeLostTrigger, TestSmileRequest, SpellCheckCollatorTest,
SimplePostToolTest, SolrXmlInZkTest, AutoAddReplicasIntegrationTest,
SolrGraphiteReporterTest, DistributedFacetPivotLargeTest,
TestSolrCloudWithKerberosAlt, TestBinaryResponseWriter, TestLegacyFieldReuse,
TestCollectionAPI, SliceStateTest, TestLegacyNumericRangeQueryBuilder,
SuggestComponentContextFilterQueryTest, CursorPagingTest,
TestMiniSolrCloudClusterSSL, SolrCoreCheckLockOnStartupTest,
TestXmlQParserPlugin, TestDynamicLoading, BadComponentTest,
TestSolrCoreProperties, TestSearchPerf, AliasIntegrationTest,
FullSolrCloudDistribCmdsTest, TestCloudPseudoReturnFields, HdfsRecoveryZkTest,
DeleteShardTest, NumericFieldsTest, BigEndianAscendingWordDeserializerTest,
ZkControllerTest, CoreAdminRequestStatusTest, TestSchemaManager,
TermVectorComponentTest, SuggesterFSTTest, DateMathParserTest,
TestCollectionAPIs, LeaderVoteWaitTimeoutTest, LeaderElectionContextKeyTest,
TestQueryTypes, ProtectedTermFilterFactoryTest,
TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory,
TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, BasicDistributedZkTest,
BasicZkTest, CollectionStateFormat2Test, CollectionsAPISolrJTest,
DistribJoinFromCollectionTest, DistributedQueueTest, HttpPartitionTest,
LIROnShardRestartTest, LIRRollingUpdatesTest, TestCloudConsistency,
TestDistribDocBasedVersion, TestLeaderElectionWithEmptyReplica,
TestPullReplicaErrorHandling, TestSizeLimitedDistributedMap,
TestSkipOverseerOperations, TestTlogReplica,
ConcurrentDeleteAndCreateCollectionTest, CustomCollectionTest,
TestRequestStatusCollectionAPI, ComputePlanActionTest]
[junit4] Completed [498/825 (1!)] on J0 in 41.93s, 4 tests, 1 error <<<
FAILURES!
[...truncated 43062 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22517/consoleText
[repro] Revision: d010cdd87eec89bf6308351b817d9ec1d5c6e14e
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=ComputePlanActionTest
-Dtests.method=testNodeLost -Dtests.seed=E831FB6565CC71BB -Dtests.multiplier=3
-Dtests.slow=true -Dtests.locale=si-LK -Dtests.timezone=America/Cayman
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] ComputePlanActionTest
[repro] ant compile-test
[...truncated 3323 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.ComputePlanActionTest" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=E831FB6565CC71BB
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=si-LK
-Dtests.timezone=America/Cayman -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[...truncated 91 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ComputePlanActionTest
[repro] Exiting with code 0
[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
