From dev-return-322445-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Wed May 16 20:01:31 2018 Return-Path: X-Original-To: archive-asf-public@cust-asf.ponee.io Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by mx-eu-01.ponee.io (Postfix) with SMTP id 5C963180669 for ; Wed, 16 May 2018 20:01:29 +0200 (CEST) Received: (qmail 57791 invoked by uid 500); 16 May 2018 18:01:23 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 57756 invoked by uid 99); 16 May 2018 18:01:22 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 16 May 2018 18:01:22 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id E62DD1A2DAD for ; Wed, 16 May 2018 18:01:21 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 1.491 X-Spam-Level: * X-Spam-Status: No, score=1.491 tagged_above=-999 required=6.31 tests=[KAM_ASCII_DIVIDERS=0.8, KAM_BADIPHTTP=2, KAM_LAZY_DOMAIN_SECURITY=1, RCVD_IN_DNSWL_MED=-2.3, T_RP_MATCHES_RCVD=-0.01, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id Ro--2DUyqzka for ; Wed, 16 May 2018 18:01:06 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id 748D65F17D for ; Wed, 16 May 2018 18:01:05 +0000 (UTC) Received: from jenkins-master.apache.org (unknown [62.210.60.235]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id 31332E00C4 for ; Wed, 16 May 2018 18:01:04 +0000 (UTC) Received: from jenkins-master.apache.org (localhost [127.0.0.1]) by jenkins-master.apache.org (ASF Mail Server at jenkins-master.apache.org) with ESMTP id 02DA8A0134 for ; Wed, 16 May 2018 18:01:02 +0000 (UTC) Date: Wed, 16 May 2018 17:53:27 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <106027528.186.1526493662014.JavaMail.jenkins@jenkins-master.apache.org> Subject: [JENKINS] Lucene-Solr-Tests-master - Build # 2530 - Unstable MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_185_34207760.1526493661707" X-Jenkins-Job: Lucene-Solr-Tests-master X-Jenkins-Result: UNSTABLE ------=_Part_185_34207760.1526493661707 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2530/ 2 tests failed. FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeIn= tegration Error Message: did not finish processing in time Stack Trace: java.lang.AssertionError: did not finish processing in time =09at __randomizedtesting.SeedInfo.seed([633326200D19E9FD:308A6490EF087C07]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMergeInteg= ration(IndexSizeTriggerTest.java:425) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger Error Message: waitFor not elapsed but produced an event Stack Trace: java.lang.AssertionError: waitFor not elapsed but produced an event =09at __randomizedtesting.SeedInfo.seed([633326200D19E9FD:F810A294D69AD0]:0= ) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.junit.Assert.assertNull(Assert.java:551) =09at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testTrigger(In= dexSizeTriggerTest.java:180) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 12430 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest [junit4] 2> 217288 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test= .solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Luce= ne-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscali= ng.IndexSizeTriggerTest_633326200D19E9FD-001/init-core-data-001 [junit4] 2> 217293 WARN (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens= =3D3 numCloses=3D3 [junit4] 2> 217293 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINT= S_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 217295 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and client= Auth (true) via: @org.apache.solr.util.RandomizeSSL(reason=3D, ssl=3DNaN, v= alue=3DNaN, clientAuth=3DNaN) [junit4] 2> 217296 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 se= rvers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/= build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_63= 3326200D19E9FD-001/tempDir-001 [junit4] 2> 217296 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 217308 INFO (Thread-164) [ ] o.a.s.c.ZkTestServer clie= nt port:0.0.0.0/0.0.0.0:0 [junit4] 2> 217308 INFO (Thread-164) [ ] o.a.s.c.ZkTestServer Star= ting server [junit4] 2> 217367 ERROR (Thread-164) [ ] o.a.z.s.ZooKeeperServer Z= KShutdownHandler is not registered, so ZooKeeper server won't take any acti= on on ERROR or SHUTDOWN server state changes [junit4] 2> 217404 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:53192 [junit4] 2> 217454 INFO (zkConnectionManagerCallback-126-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 217493 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa598= 76e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 217546 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa598= 76e6f384329b122929e70a80934569428c; jvm 1.8.0_172-b11 [junit4] 2> 217591 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 217591 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= session No SessionScavenger set, using defaults [junit4] 2> 217591 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= session node0 Scavenging every 660000ms [junit4] 2> 217602 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@40a2d020{/solr,null,= AVAILABLE} [junit4] 2> 217604 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@7f579a1c{SSL,[ssl, http/1.1]}{127= .0.0.1:54410} [junit4] 2> 217604 INFO (jetty-launcher-123-thread-1) [ ] o.e.j.s.= Server Started @217728ms [junit4] 2> 217605 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D5441= 0} [junit4] 2> 217605 ERROR (jetty-launcher-123-thread-1) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 217605 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.s.= SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLogge= rFactory [junit4] 2> 217605 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 217605 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 217605 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 217605 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-16T16:24:31.024Z [junit4] 2> 217643 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 217643 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= session No SessionScavenger set, using defaults [junit4] 2> 217643 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= session node0 Scavenging every 660000ms [junit4] 2> 217652 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@552d7e03{/solr,null,= AVAILABLE} [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@57ca6425{SSL,[ssl, http/1.1]}{127= .0.0.1:44548} [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.e.j.s.= Server Started @217777ms [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D4454= 8} [junit4] 2> 217654 ERROR (jetty-launcher-123-thread-2) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.s.= SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLogge= rFactory [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 217654 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 217655 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-16T16:24:31.074Z [junit4] 2> 217716 INFO (zkConnectionManagerCallback-130-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 217728 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 217746 INFO (zkConnectionManagerCallback-128-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 217747 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 217777 INFO (jetty-launcher-123-thread-2) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:53192/solr [junit4] 2> 217809 INFO (jetty-launcher-123-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:53192/solr [junit4] 2> 217832 INFO (zkConnectionManagerCallback-134-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 217857 INFO (zkConnectionManagerCallback-138-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 217878 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client session= id 0x10639d079e40004, likely client has closed socket [junit4] 2> 217900 INFO (zkConnectionManagerCallback-140-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 217908 INFO (zkConnectionManagerCallback-142-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 218186 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 218187 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:54410_solr [junit4] 2> 218188 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 218189 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.Overseer Overseer (id=3D73810011459158022-127.0.0.1:5= 4410_solr-n_0000000000) starting [junit4] 2> 218190 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:44548_solr [junit4] 2> 218230 INFO (zkCallback-139-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 218349 INFO (zkCallback-141-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 218350 INFO (zkConnectionManagerCallback-151-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 218352 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... = (0) -> (1) [junit4] 2> 218354 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1= :53192/solr ready [junit4] 2> 218362 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient ca= che for 2147483647 transient cores [junit4] 2> 218362 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:54410_solr [junit4] 2> 218363 INFO (zkCallback-139-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 218364 INFO (zkCallback-141-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 218373 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread Adding .auto_add_replicas and .scheduled_maintenance triggers [junit4] 2> 218391 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread Refreshing /autoscaling.json with znode version 1 [junit4] 2> 218392 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 218392 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread Processed trigger updates upto znodeVersion 1 [junit4] 2> 218472 INFO (zkCallback-150-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 218499 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger = NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:54410_so= lr, 127.0.0.1:44548_solr] [junit4] 2> 218511 DEBUG (ScheduledTrigger-289-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 218524 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread -- clean old nodeAdded markers [junit4] 2> 218524 DEBUG (OverseerAutoScalingTriggerThread-73810011459= 158022-127.0.0.1:54410_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 218914 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44548.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@84b01ca [junit4] 2> 218926 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44548.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@84b01ca [junit4] 2> 218926 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44548.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@84b01ca [junit4] 2> 218928 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/buil= d/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_633326= 200D19E9FD-001/tempDir-001/node2/. [junit4] 2> 219013 INFO (zkConnectionManagerCallback-156-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 219015 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... = (0) -> (2) [junit4] 2> 219023 INFO (jetty-launcher-123-thread-2) [n:127.0.0.1:44= 548_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1= :53192/solr ready [junit4] 2> 219316 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54410.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@84b01ca [junit4] 2> 219329 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54410.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@84b01ca [junit4] 2> 219330 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_54410.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@84b01ca [junit4] 2> 219331 INFO (jetty-launcher-123-thread-1) [n:127.0.0.1:54= 410_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/buil= d/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_633326= 200D19E9FD-001/tempDir-001/node1/. [junit4] 2> 219369 INFO (zkConnectionManagerCallback-158-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 219384 INFO (zkConnectionManagerCallback-163-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 219385 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKe= eper... (0) -> (2) [junit4] 2> 219387 INFO (SUITE-IndexSizeTriggerTest-seed#[633326200D1= 9E9FD]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 1= 27.0.0.1:53192/solr ready [junit4] 2> 219415 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .sched= uled_maintenance triggers [junit4] 2> 219415 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with zn= ode version 0 [junit4] 2> 219419 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVer= sion -1 [junit4] 2> 219420 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 0 [junit4] 2> 219434 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initi= al livenodes: [127.0.0.1:10001_solr, 127.0.0.1:10000_solr] [junit4] 2> 219435 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 219436 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVer= sion 0 [junit4] 2> 219437 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219457 DEBUG (ScheduledTrigger-298-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219480 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219500 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219514 DEBUG (ScheduledTrigger-289-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219521 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219541 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219746 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219766 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219776 INFO (TEST-IndexSizeTriggerTest.testMergeIntegrati= on-seed#[633326200D19E9FD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMer= geIntegration [junit4] 2> 219787 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219807 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219828 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219848 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219868 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219888 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219910 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219930 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219948 DEBUG (simCloudManagerPool-297-thread-7) [ ] o.a= .s.c.a.s.SimClusterStateProvider -- elected new leader for testMergeIntegra= tion_collection / shard1: {"core_node1":{ [junit4] 2> "core":"testMergeIntegration_collection_shard1_replica= _n1", [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10001_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":0, [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testMergeIntegration_collection"}} [junit4] 2> 219950 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219971 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 219991 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220011 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220032 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220052 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220073 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220093 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220114 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220134 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220154 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220180 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220200 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220220 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220241 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220261 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220281 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220289 DEBUG (simCloudManagerPool-297-thread-19) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 220289 DEBUG (simCloudManagerPool-297-thread-19) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 1 [junit4] 2> 220292 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 1 [junit4] 2> 220293 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 220293 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVer= sion 1 [junit4] 2> 220302 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220322 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220326 DEBUG (simCloudManagerPool-297-thread-20) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 220326 DEBUG (simCloudManagerPool-297-thread-20) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 2 [junit4] 2> 220327 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 2 [junit4] 2> 220328 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 220328 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVer= sion 2 [junit4] 2> 220337 DEBUG (simCloudManagerPool-297-thread-21) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 220337 DEBUG (simCloudManagerPool-297-thread-21) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 3 [junit4] 2> 220341 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 3 [junit4] 2> 220342 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 220342 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVer= sion 3 [junit4] 2> 220342 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220362 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220383 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220403 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220424 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220444 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220453 DEBUG (simCloudManagerPool-297-thread-28) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 220453 DEBUG (simCloudManagerPool-297-thread-28) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 4 [junit4] 2> 220454 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 4 [junit4] 2> 220454 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 220454 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVer= sion 4 [junit4] 2> 220465 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220485 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220505 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220514 DEBUG (ScheduledTrigger-289-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220526 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220546 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220698 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220741 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220762 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220782 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220802 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220823 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220843 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220863 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220883 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220904 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220924 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220944 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220965 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 220985 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221005 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221025 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221046 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221066 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221086 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221106 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221127 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221147 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221167 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221187 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221208 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221228 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221248 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221268 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221289 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221309 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221329 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221349 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221370 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221390 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221410 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221430 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221451 DEBUG (ScheduledTrigger-298-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221471 DEBUG (ScheduledTrigger-298-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221491 DEBUG (ScheduledTrigger-298-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221511 DEBUG (ScheduledTrigger-298-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221514 DEBUG (ScheduledTrigger-289-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221531 DEBUG (ScheduledTrigger-298-thread-1) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221552 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221741 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221762 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221782 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221802 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221822 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221842 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221863 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221883 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221903 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221924 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221944 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221964 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 221985 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222005 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222025 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222046 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222066 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222086 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222107 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222127 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222147 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222241 DEBUG (ScheduledTrigger-298-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222262 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222282 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222302 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222322 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222343 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222359 INFO (TEST-IndexSizeTriggerTest.testMergeIntegrati= on-seed#[633326200D19E9FD]) [ ] o.a.s.c.a.IndexSizeTriggerTest #########= ############################## [junit4] 2> ############ CLUSTER STATE ############ [junit4] 2> ####################################### [junit4] 2> ## Live nodes:=09=092 [junit4] 2> ## Empty nodes:=090 [junit4] 2> ## Dead nodes:=09=090 [junit4] 2> ## Collections:=09[testMergeIntegration_collection] [junit4] 2> DocCollection(testMergeIntegration_collection//clusterstat= e.json/19)=3D{ [junit4] 2> "replicationFactor":"2", [junit4] 2> "pullReplicas":"0", [junit4] 2> "router":{"name":"compositeId"}, [junit4] 2> "maxShardsPerNode":"2", [junit4] 2> "autoAddReplicas":"false", [junit4] 2> "nrtReplicas":"2", [junit4] 2> "tlogReplicas":"0", [junit4] 2> "autoCreated":"true", [junit4] 2> "shards":{ [junit4] 2> "shard2":{ [junit4] 2> "replicas":{ [junit4] 2> "core_node3":{ [junit4] 2> "core":"testMergeIntegration_collection_shard2_r= eplica_n3", [junit4] 2> "SEARCHER.searcher.maxDoc":3, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10001_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":3}, [junit4] 2> "core_node4":{ [junit4] 2> "core":"testMergeIntegration_collection_shard2_r= eplica_n4", [junit4] 2> "SEARCHER.searcher.maxDoc":3, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10000_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":3}}, [junit4] 2> "range":"0-7fffffff", [junit4] 2> "state":"active"}, [junit4] 2> "shard1":{ [junit4] 2> "replicas":{ [junit4] 2> "core_node1":{ [junit4] 2> "core":"testMergeIntegration_collection_shard1_r= eplica_n1", [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":7, [junit4] 2> "SEARCHER.searcher.deletedDocs":5, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10001_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":2}, [junit4] 2> "core_node2":{ [junit4] 2> "core":"testMergeIntegration_collection_shard1_r= eplica_n2", [junit4] 2> "SEARCHER.searcher.maxDoc":7, [junit4] 2> "SEARCHER.searcher.deletedDocs":5, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10000_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":2}}, [junit4] 2> "range":"80000000-ffffffff", [junit4] 2> "state":"active"}}} [junit4] 2> ## Max replicas per node:=092 [junit4] 2> ## Min replicas per node:=092 [junit4] 2> ## Total replicas:=09=094 [junit4] 2> ## * testMergeIntegration_collection=09=094 [junit4] 2> ##=09=09- active 4 [junit4] 2> ######### Solr op counts ########## [junit4] 2> ##=09=09- CREATE 1 [junit4] 2> ##=09=09- autoscaling 4 [junit4] 2> ##=09=09- update 17 [junit4] 2> ######### Autoscaling event counts ########### [junit4] 2>=20 [junit4] 2> 222363 DEBUG (ScheduledTrigger-298-thread-3) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222364 DEBUG (TEST-IndexSizeTriggerTest.testMergeIntegrati= on-seed#[633326200D19E9FD]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshi= ng /autoscaling.json with znode version 5 [junit4] 2> 222364 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 5 [junit4] 2> 222364 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 222364 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 222364 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVer= sion 5 [junit4] 2> 222465 INFO (TEST-IndexSizeTriggerTest.testMergeIntegrati= on-seed#[633326200D19E9FD]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMerge= Integration [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DIndexSizeTrig= gerTest -Dtests.method=3DtestMergeIntegration -Dtests.seed=3D633326200D19E9= FD -Dtests.multiplier=3D2 -Dtests.slow=3Dtrue -Dtests.locale=3Dnl-BE -Dtest= s.timezone=3DAmerica/Argentina/Rio_Gallegos -Dtests.asserts=3Dtrue -Dtests.= file.encoding=3DUS-ASCII [junit4] FAILURE 2.73s J2 | IndexSizeTriggerTest.testMergeIntegration <<= < [junit4] > Throwable #1: java.lang.AssertionError: did not finish pro= cessing in time [junit4] > =09at __randomizedtesting.SeedInfo.seed([633326200D19E9FD:= 308A6490EF087C07]:0) [junit4] > =09at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTe= st.testMergeIntegration(IndexSizeTriggerTest.java:425) [junit4] > =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> 222516 DEBUG (ScheduledTrigger-289-thread-2) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 222531 INFO (TEST-IndexSizeTriggerTest.testSplitIntegrati= on-seed#[633326200D19E9FD]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSpl= itIntegration [junit4] 2> 222673 DEBUG (simCloudManagerPool-297-thread-34) [ ] o.= a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegr= ation_collection / shard2: {"core_node3":{ [junit4] 2> "core":"testSplitIntegration_collection_shard2_replica= _n3", [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10001_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":0, [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"testSplitIntegration_collection"}} [junit4] 2> 222678 DEBUG (simCloudManagerPool-297-thread-35) [ ] o.= a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegr= ation_collection / shard1: {"core_node1":{ [junit4] 2> "core":"testSplitIntegration_collection_shard1_replica= _n1", [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":0, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10001_solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":0, [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"testSplitIntegration_collection"}} [junit4] 2> 222690 DEBUG (simCloudManagerPool-297-thread-36) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 222694 DEBUG (simCloudManagerPool-297-thread-36) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 6 [junit4] 2> 222694 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 6 [junit4] 2> 222694 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 222694 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 222694 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVer= sion 6 [junit4] 2> 222695 DEBUG (simCloudManagerPool-297-thread-37) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 222695 DEBUG (simCloudManagerPool-297-thread-37) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 7 [junit4] 2> 222695 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 7 [junit4] 2> 222695 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 222695 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 222695 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVer= sion 7 [junit4] 2> 222701 DEBUG (simCloudManagerPool-297-thread-38) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 222701 DEBUG (simCloudManagerPool-297-thread-38) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 8 [junit4] 2> 222703 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 8 [junit4] 2> 222703 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 222703 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 222703 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVer= sion 8 [junit4] 2> 223001 DEBUG (simCloudManagerPool-297-thread-90) [ ] o.= a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 223001 DEBUG (simCloudManagerPool-297-thread-90) [ ] o.= a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode versi= on 9 [junit4] 2> 223001 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znode= Version 9 [junit4] 2> 223002 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 223002 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 223002 DEBUG (Simulated OverseerAutoScalingTriggerThread) = [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 9, lastZnodeVer= sion 9 [junit4] 2> 223171 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}} [junit4] 2> 223173 DEBUG (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.ScheduledTriggers Pausing all triggers: [index_size_trigger2] [junit4] 2> 223175 INFO (ScheduledTrigger-298-thread-4) [ ] o.a.s.= c.a.IndexSizeTriggerTest =3D=3D=3D=3D=3D=3D=3D> CapturedEvent{timestamp=3D2= 6739958992684367, stage=3DSTARTED, actionName=3D'null', event=3D{ [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}}, context=3D{}, config=3D{ [junit4] 2> "trigger":"index_size_trigger2", [junit4] 2> "stage":[ [junit4] 2> "STARTED", [junit4] 2> "ABORTED", [junit4] 2> "SUCCEEDED", [junit4] 2> "FAILED"], [junit4] 2> "afterAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"], [junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTrig= gerTest$CapturingTriggerListener", [junit4] 2> "beforeAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"]}, message=3D'null'} [junit4] 2> 223228 DEBUG (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ScheduledTriggers -- processing actions for { [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}} [junit4] 2> 223233 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.IndexSizeTriggerTest =3D=3D=3D=3D=3D=3D=3D> CapturedEvent{time= stamp=3D26739961904468967, stage=3DBEFORE_ACTION, actionName=3D'compute_pla= n', event=3D{ [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}}, context=3D{properties.BEFORE_ACTION=3D[compute_plan], source=3Dindex= _size_trigger2}, config=3D{ [junit4] 2> "trigger":"index_size_trigger2", [junit4] 2> "stage":[ [junit4] 2> "STARTED", [junit4] 2> "ABORTED", [junit4] 2> "SUCCEEDED", [junit4] 2> "FAILED"], [junit4] 2> "afterAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"], [junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTrig= gerTest$CapturingTriggerListener", [junit4] 2> "beforeAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"]}, message=3D'null'} [junit4] 2> 223234 DEBUG (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ComputePlanAction -- processing event: { [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}} with context properties: {BEFORE_ACTION=3D[compute_plan]} [junit4] 2> 223236 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ComputePlanAction Computed Plan: action=3DSPLITSHARD&collectio= n=3DtestSplitIntegration_collection&shard=3Dshard1 [junit4] 2> 223237 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ComputePlanAction Computed Plan: action=3DSPLITSHARD&collectio= n=3DtestSplitIntegration_collection&shard=3Dshard2 [junit4] 2> 223237 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 26739962115 sessionW= rapper.createTime 26739961982887017, this.sessionWrapper.createTime 2673996= 1982887017=20 [junit4] 2> 223238 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.IndexSizeTriggerTest =3D=3D=3D=3D=3D=3D=3D> CapturedEvent{time= stamp=3D26739962133984817, stage=3DAFTER_ACTION, actionName=3D'compute_plan= ', event=3D{ [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "__start__":2, [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}}, context=3D{properties.operations=3D[{class=3Dorg.apache.solr.client.= solrj.request.CollectionAdminRequest$SplitShard, method=3DGET, params.actio= n=3DSPLITSHARD, params.collection=3DtestSplitIntegration_collection, params= .shard=3Dshard1}, {class=3Dorg.apache.solr.client.solrj.request.CollectionA= dminRequest$SplitShard, method=3DGET, params.action=3DSPLITSHARD, params.co= llection=3DtestSplitIntegration_collection, params.shard=3Dshard2}], proper= ties.BEFORE_ACTION=3D[compute_plan], source=3Dindex_size_trigger2, properti= es.AFTER_ACTION=3D[compute_plan]}, config=3D{ [junit4] 2> "trigger":"index_size_trigger2", [junit4] 2> "stage":[ [junit4] 2> "STARTED", [junit4] 2> "ABORTED", [junit4] 2> "SUCCEEDED", [junit4] 2> "FAILED"], [junit4] 2> "afterAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"], [junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTrig= gerTest$CapturingTriggerListener", [junit4] 2> "beforeAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"]}, message=3D'null'} [junit4] 2> 223238 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.IndexSizeTriggerTest =3D=3D=3D=3D=3D=3D=3D> CapturedEvent{time= stamp=3D26739962164160567, stage=3DBEFORE_ACTION, actionName=3D'execute_pla= n', event=3D{ [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "__start__":2, [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}}, context=3D{properties.operations=3D[{class=3Dorg.apache.solr.client.= solrj.request.CollectionAdminRequest$SplitShard, method=3DGET, params.actio= n=3DSPLITSHARD, params.collection=3DtestSplitIntegration_collection, params= .shard=3Dshard1}, {class=3Dorg.apache.solr.client.solrj.request.CollectionA= dminRequest$SplitShard, method=3DGET, params.action=3DSPLITSHARD, params.co= llection=3DtestSplitIntegration_collection, params.shard=3Dshard2}], proper= ties.BEFORE_ACTION=3D[compute_plan, execute_plan], source=3Dindex_size_trig= ger2, properties.AFTER_ACTION=3D[compute_plan]}, config=3D{ [junit4] 2> "trigger":"index_size_trigger2", [junit4] 2> "stage":[ [junit4] 2> "STARTED", [junit4] 2> "ABORTED", [junit4] 2> "SUCCEEDED", [junit4] 2> "FAILED"], [junit4] 2> "afterAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"], [junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTrig= gerTest$CapturingTriggerListener", [junit4] 2> "beforeAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"]}, message=3D'null'} [junit4] 2> 223239 DEBUG (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ExecutePlanAction -- processing event: { [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "__start__":2, [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}} with context properties: {operations=3D[org.apache.solr.client.solrj.= request.CollectionAdminRequest$SplitShard@75686d0f, org.apache.solr.client.= solrj.request.CollectionAdminRequest$SplitShard@6fcbdac4], AFTER_ACTION=3D[= compute_plan], BEFORE_ACTION=3D[compute_plan, execute_plan]} [junit4] 2> 223239 DEBUG (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ExecutePlanAction Executing operation: action=3DSPLITSHARD&col= lection=3DtestSplitIntegration_collection&shard=3Dshard1 [junit4] 2> 223516 DEBUG (ScheduledTrigger-289-thread-4) [ ] o.a.s.= c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with curren= tly live nodes: 2 [junit4] 2> 223550 DEBUG (simCloudManagerPool-297-thread-95) [ ] o.= a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegr= ation_collection / shard1_1: {"core_node10":{ [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":12, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10000_solr", [junit4] 2> "base_url":"http://127.0.0.1:10000/solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":12, [junit4] 2> "core":"testSplitIntegration_collection_shard1_1_repli= ca1", [junit4] 2> "shard":"shard1_1", [junit4] 2> "collection":"testSplitIntegration_collection"}} [junit4] 2> 223574 DEBUG (simCloudManagerPool-297-thread-96) [ ] o.= a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitIntegr= ation_collection / shard1_0: {"core_node7":{ [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":13, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10001_solr", [junit4] 2> "base_url":"http://127.0.0.1:10001/solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":13, [junit4] 2> "core":"testSplitIntegration_collection_shard1_0_repli= ca0", [junit4] 2> "shard":"shard1_0", [junit4] 2> "collection":"testSplitIntegration_collection"}} [junit4] 2> 223600 DEBUG (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.ExecutePlanAction Executing operation: action=3DSPLITSHARD&col= lection=3DtestSplitIntegration_collection&shard=3Dshard2 [junit4] 2> 223780 DEBUG (simCloudManagerPool-297-thread-102) [ ] o= .a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitInteg= ration_collection / shard2_1: {"core_node16":{ [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":12, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10000_solr", [junit4] 2> "base_url":"http://127.0.0.1:10000/solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":12, [junit4] 2> "core":"testSplitIntegration_collection_shard2_1_repli= ca1", [junit4] 2> "shard":"shard2_1", [junit4] 2> "collection":"testSplitIntegration_collection"}} [junit4] 2> 223780 DEBUG (simCloudManagerPool-297-thread-105) [ ] o= .a.s.c.a.s.SimClusterStateProvider -- elected new leader for testSplitInteg= ration_collection / shard2_0: {"core_node14":{ [junit4] 2> "leader":"true", [junit4] 2> "SEARCHER.searcher.maxDoc":13, [junit4] 2> "SEARCHER.searcher.deletedDocs":0, [junit4] 2> "INDEX.sizeInBytes":1, [junit4] 2> "node_name":"127.0.0.1:10000_solr", [junit4] 2> "base_url":"http://127.0.0.1:10000/solr", [junit4] 2> "state":"active", [junit4] 2> "type":"NRT", [junit4] 2> "SEARCHER.searcher.numDocs":13, [junit4] 2> "core":"testSplitIntegration_collection_shard2_0_repli= ca1", [junit4] 2> "shard":"shard2_0", [junit4] 2> "collection":"testSplitIntegration_collection"}} [junit4] 2> 223796 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.IndexSizeTriggerTest =3D=3D=3D=3D=3D=3D=3D> CapturedEvent{time= stamp=3D26739990045943567, stage=3DAFTER_ACTION, actionName=3D'execute_plan= ', event=3D{ [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "__start__":2, [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard1\"}]}= }", [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_collection\",\n \"second\":\"shard2\"}]}= }"]}}, context=3D{properties.operations=3D[{class=3Dorg.apache.solr.client.= solrj.request.CollectionAdminRequest$SplitShard, method=3DGET, params.actio= n=3DSPLITSHARD, params.async=3Dindex_size_trigger2/5effd7da54be83Tdz12trydt= a1f9960kx3i6dkzm/0, params.waitForFinalState=3Dtrue, params.collection=3Dte= stSplitIntegration_collection, params.shard=3Dshard1}, {class=3Dorg.apache.= solr.client.solrj.request.CollectionAdminRequest$SplitShard, method=3DGET, = params.action=3DSPLITSHARD, params.async=3Dindex_size_trigger2/5effd7da54be= 83Tdz12trydta1f9960kx3i6dkzm/0, params.waitForFinalState=3Dtrue, params.col= lection=3DtestSplitIntegration_collection, params.shard=3Dshard2}], propert= ies.BEFORE_ACTION=3D[compute_plan, execute_plan], properties.responses=3D[{= status=3D{state=3Dcompleted,msg=3Dfound [index_size_trigger2/5effd7da54be83= Tdz12trydta1f9960kx3i6dkzm/0] in completed tasks},success=3D}, {status=3D{s= tate=3Dcompleted,msg=3Dfound [index_size_trigger2/5effd7da54be83Tdz12trydta= 1f9960kx3i6dkzm/0] in completed tasks},success=3D}], source=3Dindex_size_tr= igger2, properties.AFTER_ACTION=3D[compute_plan, execute_plan]}, config=3D{ [junit4] 2> "trigger":"index_size_trigger2", [junit4] 2> "stage":[ [junit4] 2> "STARTED", [junit4] 2> "ABORTED", [junit4] 2> "SUCCEEDED", [junit4] 2> "FAILED"], [junit4] 2> "afterAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"], [junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTrig= gerTest$CapturingTriggerListener", [junit4] 2> "beforeAction":[ [junit4] 2> "compute_plan", [junit4] 2> "execute_plan"]}, message=3D'null'} [junit4] 2> 223797 INFO (AutoscalingActionExecutor-299-thread-1) [ = ] o.a.s.c.a.IndexSizeTriggerTest =3D=3D=3D=3D=3D=3D=3D> CapturedEvent{time= stamp=3D26739990103470367, stage=3DSUCCEEDED, actionName=3D'null', event=3D= { [junit4] 2> "id":"5effd7da54be83Tdz12trydta1f9960kx3i6dkzm", [junit4] 2> "source":"index_size_trigger2", [junit4] 2> "eventTime":26739950356840067, [junit4] 2> "eventType":"INDEXSIZE", [junit4] 2> "properties":{ [junit4] 2> "__start__":2, [junit4] 2> "aboveSize":{"testSplitIntegration_collection":[ [junit4] 2> "{\"core_node1\":{\n \"core\":\"testSplitIntegr= ation_collection_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard1\",\n \"collection\":\"testSplitIn= tegration_collection\"}}", [junit4] 2> "{\"core_node3\":{\n \"core\":\"testSplitIntegr= ation_collection_shard2_replica_n3\",\n \"leader\":\"true\",\n \"SEAR= CHER.searcher.maxDoc\":25,\n \"SEARCHER.searcher.deletedDocs\":0,\n \= "INDEX.sizeInBytes\":1,\n \"__docs__\":25,\n \"violationType\":\"abov= eDocs\",\n \"node_name\":\"127.0.0.1:10001_solr\",\n \"state\":\"acti= ve\",\n \"type\":\"NRT\",\n \"SEARCHER.searcher.numDocs\":25,\n \"= __bytes__\":1,\n \"shard\":\"shard2\",\n \"collection\":\"testSplitIn= tegration_collection\"}}"]}, [junit4] 2> "belowSize":{}, [junit4] 2> "_enqueue_time_":26739958929590817, [junit4] 2> "requestedOps":[ [junit4] 2> "Op{action=3DSPLITSHARD, hints=3D{COLL_SHARD=3D[{\n = \"first\":\"testSplitIntegration_coll [...truncated too long message...] pace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Te= sts-master/lucene/null1208031599 [copy] Copying 39 files to /x1/jenkins/jenkins-slave/workspace/Lucene-= Solr-Tests-master/lucene/null1208031599 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-S= olr-Tests-master/lucene/null1208031599 resolve-example: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: resolve-server: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /x1/jenkins/jenkins-slave/w= orkspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Te= sts-master/solr/null394833958 [copy] Copying 246 files to /x1/jenkins/jenkins-slave/workspace/Lucene= -Solr-Tests-master/solr/null394833958 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-S= olr-Tests-master/solr/null394833958 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.j= git-caller;working [ivy:cachepath] =09confs: [default] [ivy:cachepath] =09found org.eclipse.jgit#org.eclipse.jgit;4.6.0.2016122319= 35-r in public [ivy:cachepath] =09found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] =09found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] =09found org.apache.httpcomponents#httpclient;4.3.6 in publ= ic [ivy:cachepath] =09found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] =09found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] =09found commons-codec#commons-codec;1.6 in public [ivy:cachepath] =09found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 42ms :: artifacts dl 2ms =09--------------------------------------------------------------------- =09| | modules || artifacts | =09| conf | number| search|dwnlded|evicted|| number|dwnlded| =09--------------------------------------------------------------------- =09| default | 8 | 0 | 0 | 0 || 8 | 0 | =09--------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder= ". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder = for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 157 minutes 43 seconds Archiving artifacts WARN: No artifacts found that match the file pattern "**/*.events,heapdumps= /**,**/hs_err_pid*". Configuration error? WARN: java.lang.InterruptedException: no matches found within 10000 Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTA= BLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) ------=_Part_185_34207760.1526493661707 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_185_34207760.1526493661707--