Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/732/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost Error Message: Error from server at http://127.0.0.1:46450/solr: create the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46450/solr: create the collection time out:180s at __randomizedtesting.SeedInfo.seed([E75105577775B87C:D791E4D5FF075920]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) 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.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeWithMultipleReplicasLost(ComputePlanActionTest.java:269) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 14150 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/init-core-data-001 [junit4] 2> 2774869 WARN (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=30 numCloses=30 [junit4] 2> 2774869 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2774870 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 2774870 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 2774871 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001 [junit4] 2> 2774871 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2774875 INFO (Thread-7101) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2774875 INFO (Thread-7101) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2774877 ERROR (Thread-7101) [ ] 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> 2774972 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:57147 [junit4] 2> 2774983 INFO (zkConnectionManagerCallback-8707-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2775023 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 2775024 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2775024 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2775024 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 2775024 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7dbc915d{/solr,null,AVAILABLE} [junit4] 2> 2775025 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3f39f46f{HTTP/1.1,[http/1.1]}{127.0.0.1:36679} [junit4] 2> 2775025 INFO (jetty-launcher-8704-thread-1) [ ] o.e.j.s.Server Started @2776547ms [junit4] 2> 2775025 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36679} [junit4] 2> 2775031 ERROR (jetty-launcher-8704-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2775032 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2775032 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0 [junit4] 2> 2775032 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2775032 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2775032 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-19T09:43:08.681Z [junit4] 2> 2775040 INFO (zkConnectionManagerCallback-8709-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2775051 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2776211 INFO (jetty-launcher-8704-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57147/solr [junit4] 2> 2776225 INFO (zkConnectionManagerCallback-8713-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2776231 INFO (zkConnectionManagerCallback-8715-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2776604 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36679_solr [junit4] 2> 2776606 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.c.Overseer Overseer (id=72533543368458243-127.0.0.1:36679_solr-n_0000000000) starting [junit4] 2> 2776645 INFO (zkConnectionManagerCallback-8722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2776658 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57147/solr ready [junit4] 2> 2776661 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36679_solr [junit4] 2> 2776661 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 2776661 INFO (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:36679_solr [junit4] 2> 2776663 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 2776663 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 2776663 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 2776671 INFO (zkCallback-8721-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2776671 INFO (zkCallback-8714-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2776686 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:36679_solr] [junit4] 2> 2776686 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2776687 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 2776690 DEBUG (ScheduledTrigger-10763-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 [junit4] 2> 2776721 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2776755 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36679.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2776768 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36679.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2776768 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36679.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2776769 INFO (jetty-launcher-8704-thread-1) [n:127.0.0.1:36679_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001/node1/. [junit4] 2> 2776821 INFO (zkConnectionManagerCallback-8725-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2776831 INFO (zkConnectionManagerCallback-8730-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2776856 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2776862 INFO (SUITE-ComputePlanActionTest-seed#[E75105577775B87C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57147/solr ready [junit4] 2> 2777124 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAdded [junit4] 2> 2777129 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2 [junit4] 2> 2777129 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 2777135 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2777147 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2777148 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2777151 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 2777152 INFO (qtp2080501275-31604) [n:127.0.0.1:36679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3 [junit4] 2> 2777152 DEBUG (qtp2080501275-31609) [n:127.0.0.1:36679_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 2777154 INFO (qtp2080501275-31609) [n:127.0.0.1:36679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=8 [junit4] 2> 2777155 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3 [junit4] 2> 2777155 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 2777155 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2777156 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2777156 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 2777157 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2777157 DEBUG (qtp2080501275-31608) [n:127.0.0.1:36679_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 2777158 INFO (qtp2080501275-31608) [n:127.0.0.1:36679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=3 [junit4] 2> 2777159 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4 [junit4] 2> 2777159 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 2777159 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2777160 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2777167 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 2777182 INFO (qtp2080501275-31608) [n:127.0.0.1:36679_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> 2777182 DEBUG (qtp2080501275-31607) [n:127.0.0.1:36679_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 2777185 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5 [junit4] 2> 2777185 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=6 [junit4] 2> 2777188 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 2777191 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2777191 DEBUG (qtp2080501275-31611) [n:127.0.0.1:36679_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 2777192 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=4 [junit4] 2> 2777193 INFO (qtp2080501275-31608) [n:127.0.0.1:36679_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> 2777193 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6 [junit4] 2> 2777193 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - Initial livenodes: [127.0.0.1:36679_solr] [junit4] 2> 2777193 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_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> 2777194 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2777194 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 5 [junit4] 2> 2777195 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 2777196 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2777196 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2777196 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 2777196 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 2777197 INFO (OverseerThreadFactory-10765-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection testNodeAdded [junit4] 2> 2777198 WARN (OverseerThreadFactory-10765-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> 2777201 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2777202 INFO (OverseerThreadFactory-10765-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 7262412765 sessionWrapper.createTime 7262412764818307, this.sessionWrapper.createTime 7262412764818307 [junit4] 2> 2777208 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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> 2777208 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: testNodeAdded [junit4] 2> 2777210 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testNodeAdded/state.json [junit4] 2> 2777333 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 2777333 INFO (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2777337 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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> 2777337 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 2777340 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} current state version: 0 [junit4] 2> 2777340 INFO (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2777342 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 2777342 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/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":"http://127.0.0.1:36679/solr";, [junit4] 2> "state":"down", [junit4] 2> "node_name":null, [junit4] 2> "type":"NRT"}}} [junit4] 2> 2777445 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 0 [junit4] 2> 2777553 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2777553 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2777563 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2777563 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2777563 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:36679/solr","node_name":"127.0.0.1:36679_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 2777563 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 2777570 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2777583 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "core":"testNodeAdded_shard1_replica_n1", [junit4] 2> "core_node_name":"core_node3", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2777583 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2777584 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:36679/solr","node_name":"127.0.0.1:36679_solr","state":"down","type":"NRT","force_set_state":"false"} [junit4] 2> 2777584 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_solr", [junit4] 2> "state":"down", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false"}}}}} [junit4] 2> 2777688 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 1 [junit4] 2> 2778198 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2778198 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 2778585 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0 [junit4] 2> 2778599 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778602 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778602 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778602 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36679.solr.core.testNodeAdded.shard1.replica_n2' (registry 'solr.core.testNodeAdded.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2778602 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2778602 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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 [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001/node1/testNodeAdded_shard1_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n2/data/] [junit4] 2> 2778611 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.5.0 [junit4] 2> 2778622 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778624 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778624 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778625 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36679.solr.core.testNodeAdded.shard1.replica_n1' (registry 'solr.core.testNodeAdded.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2778625 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2778625 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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 [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001/node1/testNodeAdded_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001/node1/./testNodeAdded_shard1_replica_n1/data/] [junit4] 2> 2778722 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778722 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778724 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2778724 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2778726 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2311dd3c[testNodeAdded_shard1_replica_n1] main] [junit4] 2> 2778733 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778733 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778734 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2778734 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2778736 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@76cb6e95[testNodeAdded_shard1_replica_n2] main] [junit4] 2> 2778744 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778744 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778745 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778745 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778745 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778746 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778746 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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 1606411503423979520 [junit4] 2> 2778746 INFO (searcherExecutor-10771-thread-1-processing-n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n1 c:testNodeAdded s:shard1 r:core_node3) [n:127.0.0.1:36679_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@2311dd3c[testNodeAdded_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2778746 INFO (searcherExecutor-10770-thread-1-processing-n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2 c:testNodeAdded s:shard1 r:core_node4) [n:127.0.0.1:36679_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@76cb6e95[testNodeAdded_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2778746 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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 1606411503423979520 [junit4] 2> 2778763 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778763 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node3 x:testNodeAdded_shard1_replica_n1] o.a.s.c.ZkShardTerms Failed to save terms, version is not a match, retrying [junit4] 2> 2778767 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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> 2778788 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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> 2778791 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778791 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778791 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36679/solr/testNodeAdded_shard1_replica_n2/ [junit4] 2> 2778793 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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=http://127.0.0.1:36679/solr START replicas=[http://127.0.0.1:36679/solr/testNodeAdded_shard1_replica_n1/] nUpdates=100 [junit4] 2> 2778794 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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=http://127.0.0.1:36679/solr DONE. We have no versions. sync failed. [junit4] 2> 2778803 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_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> 2778804 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778804 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778804 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778828 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr c:testNodeAdded s:shard1 r:core_node4 x:testNodeAdded_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36679/solr/testNodeAdded_shard1_replica_n2/ shard1 [junit4] 2> 2778831 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testNodeAdded", [junit4] 2> "base_url":"http://127.0.0.1:36679/solr";, [junit4] 2> "core":"testNodeAdded_shard1_replica_n2", [junit4] 2> "state":"active"} current state version: 0 [junit4] 2> 2778932 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 2 [junit4] 2> 2778937 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2778940 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2778940 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2778940 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{"core":"testNodeAdded_shard1_replica_n2","base_url":"http://127.0.0.1:36679/solr","node_name":"127.0.0.1:36679_solr","state":"active","type":"NRT","force_set_state":"false","leader":"true"} [junit4] 2> 2778940 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testNodeAdded//collections/testNodeAdded/state.json/3)={ [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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "leader":"true"}}}}} [junit4] 2> 2778941 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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=1391 [junit4] 2> 2779042 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 3 [junit4] 2> 2779043 INFO (zkCallback-8714-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> 2779199 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2779199 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 2779796 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2779796 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2779796 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{"core":"testNodeAdded_shard1_replica_n1","base_url":"http://127.0.0.1:36679/solr","node_name":"127.0.0.1:36679_solr","state":"active","type":"NRT","force_set_state":"false"} [junit4] 2> 2779796 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "force_set_state":"false", [junit4] 2> "leader":"true"}}}}} [junit4] 2> 2779797 INFO (qtp2080501275-31611) [n:127.0.0.1:36679_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=2228 [junit4] 2> 2779816 INFO (qtp2080501275-31608) [n:127.0.0.1:36679_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> 2779897 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testNodeAdded/state.json version: 4 [junit4] 2> 2779897 INFO (zkCallback-8714-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> 2780199 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2780200 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 2780817 INFO (qtp2080501275-31608) [n:127.0.0.1:36679_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=3626 [junit4] 2> 2780823 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2780825 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_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=1 [junit4] 2> 2780826 DEBUG (qtp2080501275-31609) [n:127.0.0.1:36679_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 2780827 INFO (qtp2080501275-31609) [n:127.0.0.1:36679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=7 [junit4] 2> 2780828 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7 [junit4] 2> 2780828 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 2780828 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11 [junit4] 2> 2780828 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2780829 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 2780829 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2780829 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2780829 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 2780830 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@70d0700c{/solr,null,AVAILABLE} [junit4] 2> 2780830 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3a6533b0{HTTP/1.1,[http/1.1]}{127.0.0.1:34285} [junit4] 2> 2780830 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.e.j.s.Server Started @2782356ms [junit4] 2> 2780830 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34285} [junit4] 2> 2780830 ERROR (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2780832 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2780832 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.5.0 [junit4] 2> 2780832 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2780832 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2780832 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-19T09:43:14.481Z [junit4] 2> 2780834 INFO (zkConnectionManagerCallback-8734-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2780835 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2781203 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2781203 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 [junit4] 2> 2781211 INFO (OverseerCollectionConfigSetProcessor-72533543368458243-127.0.0.1:36679_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> 2781269 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57147/solr [junit4] 2> 2781271 INFO (zkConnectionManagerCallback-8738-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2781276 INFO (zkConnectionManagerCallback-8740-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2781301 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2781320 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2781320 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34285_solr [junit4] 2> 2781320 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:34285_solr"} current state version: 0 [junit4] 2> 2781320 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:34285_solr [junit4] 2> 2781322 INFO (zkCallback-8714-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2781322 INFO (zkCallback-8729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2781322 INFO (zkCallback-8729-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2781322 INFO (zkCallback-8721-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2781322 INFO (zkCallback-8739-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2781391 INFO (zkConnectionManagerCallback-8747-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2781395 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2781396 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57147/solr ready [junit4] 2> 2781397 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 2781420 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34285.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2781430 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34285.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2781430 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34285.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2f838f89 [junit4] 2> 2781431 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [n:127.0.0.1:34285_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ComputePlanActionTest_E75105577775B87C-001/tempDir-001/node2/. [junit4] 2> 2782205 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2782205 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 2782205 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:34285_solr at time 7262417770938548 [junit4] 2> 2783207 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger [junit4] 2> 2783207 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 2783207 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:34285_solr] added at times [7262417770938548], now=7262418773473495 [junit4] 2> 2783207 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"19cd21677280b4Ta1jwucb95f2hknxgu42als9x4", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":7262417770938548, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[7262417770938548], [junit4] 2> "nodeNames":["127.0.0.1:34285_solr"]}} [junit4] 2> 2783210 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger] [junit4] 2> 2783211 INFO (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging. [junit4] 2> 2783211 DEBUG (ScheduledTrigger-10763-thread-2) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - removing marker path: /autoscaling/nodeAdded/127.0.0.1:34285_solr [junit4] 2> 2783211 DEBUG (AutoscalingActionExecutor-10764-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"19cd21677280b4Ta1jwucb95f2hknxgu42als9x4", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":7262417770938548, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[7262417770938548], [junit4] 2> "_enqueue_time_":7262418776440452, [junit4] 2> "nodeNames":["127.0.0.1:34285_solr"]}} [junit4] 2> 2783211 DEBUG (AutoscalingActionExecutor-10764-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: { [junit4] 2> "id":"19cd21677280b4Ta1jwucb95f2hknxgu42als9x4", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":7262417770938548, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[7262417770938548], [junit4] 2> "_enqueue_time_":7262418776440452, [junit4] 2> "nodeNames":["127.0.0.1:34285_solr"]}} with context properties: {BEFORE_ACTION=[compute_plan]} [junit4] 2> 2783221 INFO (qtp1977462798-31667) [n:127.0.0.1:34285_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> 2783222 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2783223 INFO (qtp2080501275-31609) [n:127.0.0.1:36679_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> 2783225 INFO (AutoscalingActionExecutor-10764-thread-1) [ ] o.a.s.c.a.ComputePlanAction Computed Plan: action=MOVEREPLICA&collection=testNodeAdded&targetNode=127.0.0.1:34285_solr&inPlaceMove=true&replica=core_node3 [junit4] 2> 2783225 INFO (AutoscalingActionExecutor-10764-thread-1) [ ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 7262418791 sessionWrapper.createTime 7262418790275940, this.sessionWrapper.createTime 7262418790275940 [junit4] 2> 2783225 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.a.ComputePlanActionTest -------------_ FINAL STATE -------------- [junit4] 2> 2783228 DEBUG (AutoscalingActionExecutor-10764-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after 5000ms [junit4] 2> 2783228 DEBUG (AutoscalingActionExecutor-10764-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 17 ms for event id=19cd21677280b4Ta1jwucb95f2hknxgu42als9x4 [junit4] 2> 2783240 INFO (qtp1977462798-31663) [n:127.0.0.1:34285_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=11 [junit4] 2> 2783240 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:34285_solr [junit4] 2> { [junit4] 2> "node":"127.0.0.1:34285_solr", [junit4] 2> "cores":0, [junit4] 2> "port":"34285", [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":11.717687129974365} [junit4] 2> 2783248 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2783249 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.a.ComputePlanActionTest * Node values: 127.0.0.1:36679_solr [junit4] 2> { [junit4] 2> "node":"127.0.0.1:36679_solr", [junit4] 2> "cores":2, [junit4] 2> "port":"36679", [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":11.717687129974365} [junit4] 2> 2783249 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.a.ComputePlanActionTest * Live nodes: [127.0.0.1:34285_solr, 127.0.0.1:36679_solr] [junit4] 2> 2783250 DEBUG (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.c.a.ComputePlanActionTest * Collection testNodeAdded state: DocCollection(testNodeAdded//collections/testNodeAdded/state.json/5)={ [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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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":"http://127.0.0.1:36679/solr";, [junit4] 2> "node_name":"127.0.0.1:36679_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> 2783251 INFO (TEST-ComputePlanActionTest.testNodeAdded-seed#[E75105577775B87C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNodeAdded [junit4] 2> 2783543 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSelectedCollections [junit4] 2> 2783549 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3a6533b0{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 2783549 DEBUG (zkCallback-8721-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8 [junit4] 2> 2783550 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 2783553 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1298800240 [junit4] 2> 2783554 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2783554 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c2dc0df: rootName = solr_34285, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4123e812 [junit4] 2> 2783561 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 2783562 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 2783562 DEBUG (OverseerAutoScalingTriggerThread-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8 [junit4] 2> 2783563 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2783563 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48430b1f: rootName = solr_34285, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5cd0a009 [junit4] 2> 2783568 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2783568 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76f24c22: rootName = solr_34285, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@350e4966 [junit4] 2> 2783570 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34285_solr [junit4] 2> 2783572 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 2783572 INFO (zkCallback-8746-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2783573 INFO (zkCallback-8729-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2783573 INFO (zkCallback-8739-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2783573 INFO (zkCallback-8714-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2783573 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:34285_solr"} current state version: 0 [junit4] 2> 2783573 DEBUG (OverseerStateUpdate-72533543368458243-127.0.0.1:36679_solr-n_0000000000) [ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:34285_solr [junit4] 2> 2783573 INFO (zkCallback-8729-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2783574 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x101b0df96c7000a, likely client has closed socket [junit4] 2> 2783575 INFO (zkCallback-8721-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2783579 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@70d0700c{/solr,null,UNAVAILABLE} [junit4] 2> 2783579 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 2783583 INFO (TEST-ComputePlanActionTest.testSelectedCollections-seed#[E75105577775B87C]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2783589 INFO (qtp2080501275-31609) [n:127.0.0.1:36679_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> 2783592 INFO (OverseerThreadFactory-10765-thread-2) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null [junit4] 2> 2783595 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_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> 2783595 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60061b0f: rootName = solr_36679, 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@3ea9628e [junit4] 2> 2783603 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_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> 2783603 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2] o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@57e27925 [junit4] 2> 2783603 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@64020803: rootName = solr_36679, 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@438c4d25 [junit4] 2> 2783617 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n1] o.a.s.c.SolrCore [testNodeAdded_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@a808469 [junit4] 2> 2783617 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testNodeAdded.shard1.replica_n2, tag=57e27925 [junit4] 2> 2783618 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testNodeAdded.shard1.leader, tag=57e27925 [junit4] 2> 2783618 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testNodeAdded.shard1.replica_n1, tag=a808469 [junit4] 2> 2783618 INFO (qtp2080501275-31607) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testNodeAdded.shard1.leader, tag=a808469 [junit4] 2> 2783620 INFO (qtp2080501275-31606) [n:127.0.0.1:36679_solr x:testNodeAdded_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testNodeAdded/terms/shard1 to Terms{values={core_node3=0}, version=2} [junit4] 2> 2783622 WARN (zkCallback-8714-thread-1) [ ] o.a.s.c.LeaderElector [junit4] 2> org.apache.solr.common.SolrException: SolrCore not found:testNodeAdded_shard1_replica_n1 in [] [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:312) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$1.lambda$process$1(SolrZkClient.java:270) ~[java/:?] [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_172] [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_172] [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) ~[java/:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_172] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_172] [junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172] [ [...truncated too long message...] s.xml install-junit4-taskdef: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: resolve-groovy: [ivy:cachepath] :: resolving dependencies :: org.codehaus.groovy#groovy-all-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.codehaus.groovy#groovy-all;2.4.15 in public [ivy:cachepath] :: resolution report :: resolve 14ms :: artifacts dl 1ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 1 | 0 | 0 | 0 || 1 | 0 | --------------------------------------------------------------------- -init-totals: -test: [mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test [junit4:pickseed] Seed property 'tests.seed' already defined: E75105577775B87C [mkdir] Created dir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/temp [junit4] <JUnit4> says 你好! Master seed: E75105577775B87C [junit4] Executing 5 suites with 2 JVMs. [junit4] [junit4] Started J0 PID(20158@serv1-vm3). [junit4] Started J1 PID(20157@serv1-vm3). [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] OK 7.62s J1 | ComputePlanActionTest.testNodeAdded [junit4] OK 15.7s J1 | ComputePlanActionTest.testSelectedCollections [junit4] OK 18.7s J1 | ComputePlanActionTest.testNodeLost [junit4] OK 7.05s J1 | ComputePlanActionTest.testNodeWithMultipleReplicasLost [junit4] Completed [1/5] on J1 in 54.79s, 4 tests [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] OK 6.70s J0 | ComputePlanActionTest.testNodeAdded [junit4] OK 17.1s J0 | ComputePlanActionTest.testSelectedCollections [junit4] OK 18.5s J0 | ComputePlanActionTest.testNodeLost [junit4] OK 8.80s J0 | ComputePlanActionTest.testNodeWithMultipleReplicasLost [junit4] Completed [2/5] on J0 in 59.06s, 4 tests [junit4] [junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.autoscaling.ComputePlanActionTest. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message. [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] OK 5.08s J1 | ComputePlanActionTest.testNodeAdded [junit4] OK 15.4s J1 | ComputePlanActionTest.testSelectedCollections [junit4] OK 18.9s J1 | ComputePlanActionTest.testNodeLost [junit4] OK 8.09s J1 | ComputePlanActionTest.testNodeWithMultipleReplicasLost [junit4] Completed [3/5] on J1 in 50.97s, 4 tests [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] OK 6.08s J0 | ComputePlanActionTest.testNodeAdded [junit4] OK 15.9s J0 | ComputePlanActionTest.testSelectedCollections [junit4] OK 17.4s J0 | ComputePlanActionTest.testNodeLost [junit4] OK 7.32s J0 | ComputePlanActionTest.testNodeWithMultipleReplicasLost [junit4] Completed [4/5] on J0 in 50.20s, 4 tests [junit4] [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [junit4] OK 6.07s J1 | ComputePlanActionTest.testNodeAdded [junit4] OK 15.7s J1 | ComputePlanActionTest.testSelectedCollections [junit4] OK 19.6s J1 | ComputePlanActionTest.testNodeLost [junit4] OK 8.01s J1 | ComputePlanActionTest.testNodeWithMultipleReplicasLost [junit4] Completed [5/5] on J1 in 52.96s, 4 tests [junit4] [junit4] JVM J0: 0.69 .. 110.88 = 110.20s [junit4] JVM J1: 0.69 .. 160.66 = 159.98s [junit4] Execution time total: 2 minutes 40 seconds [junit4] Tests summary: 5 suites, 20 tests [junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp]) [junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp]) [echo] 5 slowest tests: [junit4:tophints] 210.21s | org.apache.solr.update.TestInPlaceUpdatesDistrib [junit4:tophints] 116.92s | org.apache.solr.update.SoftAutoCommitTest [junit4:tophints] 116.69s | org.apache.solr.cloud.ZkFailoverTest [junit4:tophints] 112.01s | org.apache.solr.cloud.TestTlogReplica [junit4:tophints] 99.01s | org.apache.solr.cloud.TestPullReplica -check-totals: test-nocompile: BUILD SUCCESSFUL Total time: 2 minutes 43 seconds [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ComputePlanActionTest [repro] Exiting with code 0 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2843) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2722) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2703) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3082) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1089) at hudson.FilePath.act(FilePath.java:1078) at hudson.FilePath.validateAntFileMask(FilePath.java:2701) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1840) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3084) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1091) at hudson.FilePath.act(FilePath.java:1078) at hudson.FilePath.validateAntFileMask(FilePath.java:2701) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1840) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error? [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=2cb716952054132a2c267285063658681a9116bb, workspace=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris [WARNINGS] Computing warning deltas based on reference build #731 Recording test results Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
