From dev-return-323397-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Fri May 25 09:46:33 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 00A27180627 for ; Fri, 25 May 2018 09:46:30 +0200 (CEST) Received: (qmail 52623 invoked by uid 500); 25 May 2018 07:46:29 -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 52603 invoked by uid 99); 25 May 2018 07:46:29 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 25 May 2018 07:46:29 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id 6E579C0365 for ; Fri, 25 May 2018 07:46:28 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -0.05 X-Spam-Level: X-Spam-Status: No, score=-0.05 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, KAM_LOTSOFHASH=0.25, RCVD_IN_DNSWL_MED=-2.3, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id tu4CKdzqdp85 for ; Fri, 25 May 2018 07:46:11 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [88.99.242.108]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id D40A25F581 for ; Fri, 25 May 2018 07:46:10 +0000 (UTC) Received: from serv1 (ip6-localhost [IPv6:::1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id B3BAC10800D0; Fri, 25 May 2018 07:46:10 +0000 (UTC) Date: Fri, 25 May 2018 07:44:34 +0000 (UTC) From: Policeman Jenkins Server To: simonw@apache.org, sarowe@apache.org, dev@lucene.apache.org Message-ID: <723714216.21.1527234370737.JavaMail.jenkins@serv1.sd-datasolutions.de> Subject: [JENKINS] Lucene-Solr-master-Linux (64bit/jdk-10) - Build # 22099 - Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_20_1308342125.1527234370680" X-Jenkins-Job: Lucene-Solr-master-Linux X-Jenkins-Result: UNSTABLE ------=_Part_20_1308342125.1527234370680 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22099/ Java: 64bit/jdk-10 -XX:+UseCompressedOops -XX:+UseParallelGC 2 tests failed. FAILED: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testTrigge= r Error Message: expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: expected:<1> but was:<2> =09at __randomizedtesting.SeedInfo.seed([F6BD4087D0C1623E:95767605490E1113]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:128) =09at org.junit.Assert.assertEquals(Assert.java:472) =09at org.junit.Assert.assertEquals(Assert.java:456) =09at org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testTrigger(S= earchRateTriggerTest.java:133) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:564) =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 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.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testTrigge= r Error Message: expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: expected:<1> but was:<2> =09at __randomizedtesting.SeedInfo.seed([F6BD4087D0C1623E:95767605490E1113]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:128) =09at org.junit.Assert.assertEquals(Assert.java:472) =09at org.junit.Assert.assertEquals(Assert.java:456) =09at org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testTrigger(S= earchRateTriggerTest.java:133) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:564) =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 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 14646 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-mast= er-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.SearchRat= eTriggerTest_F6BD4087D0C1623E-001/init-core-data-001 [junit4] 2> 1631686 INFO (SUITE-SearchRateTriggerTest-seed#[F6BD4087D= 0C1623E]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 = servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr= -core/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C= 1623E-001/tempDir-001 [junit4] 2> 1631686 INFO (SUITE-SearchRateTriggerTest-seed#[F6BD4087D= 0C1623E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1631686 INFO (Thread-5371) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1631686 INFO (Thread-5371) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1631692 ERROR (Thread-5371) [ ] o.a.z.s.ZooKeeperServer= ZKShutdownHandler is not registered, so ZooKeeper server won't take any ac= tion on ERROR or SHUTDOWN server state changes [junit4] 2> 1631786 INFO (SUITE-SearchRateTriggerTest-seed#[F6BD4087D= 0C1623E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:41117 [junit4] 2> 1631788 INFO (zkConnectionManagerCallback-7783-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631789 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa5= 9876e6f384329b122929e70a80934569428c; jvm 10+46 [junit4] 2> 1631789 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa5= 9876e6f384329b122929e70a80934569428c; jvm 10+46 [junit4] 2> 1631789 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa5= 9876e6f384329b122929e70a80934569428c; jvm 10+46 [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.Server jetty-9.4.10.v20180503; built: 2018-05-03T15:56:21.710Z; git: daa5= 9876e6f384329b122929e70a80934569428c; jvm 10+46 [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.session node0 Scavenging every 600000ms [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.session node0 Scavenging every 600000ms [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15bc8bf7{/solr,nul= l,AVAILABLE} [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.session node0 Scavenging every 600000ms [junit4] 2> 1631790 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11ca976a{/solr,nul= l,AVAILABLE} [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@3f86c70e{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:42199} [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.e.j.= s.Server Started @1631815ms [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D42= 199} [junit4] 2> 1631791 ERROR (jetty-launcher-7780-thread-2) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLog= gerFactory [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T07:22:02.7784= 12Z [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.session No SessionScavenger set, using defaults [junit4] 2> 1631791 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.session node0 Scavenging every 660000ms [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@6196e00f{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:42221} [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.e.j.= s.Server Started @1631816ms [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D42= 221} [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@93d3718{/solr,null= ,AVAILABLE} [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.h.ContextHandler Started o.e.j.s.ServletContextHandler@179fea48{/solr,nul= l,AVAILABLE} [junit4] 2> 1631792 ERROR (jetty-launcher-7780-thread-4) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLog= gerFactory [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@5a3759ee{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:45285} [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.AbstractConnector Started ServerConnector@6f1f61e1{HTTP/1.1,[http/1.1]}{1= 27.0.0.1:39323} [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.e.j.= s.Server Started @1631817ms [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T07:22:02.7794= 64Z [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D39= 323} [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.e.j.= s.Server Started @1631817ms [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= c.s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D45= 285} [junit4] 2> 1631792 ERROR (jetty-launcher-7780-thread-3) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1631792 ERROR (jetty-launcher-7780-thread-1) [ ] o.a.s.= u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be miss= ing or incomplete. [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLog= gerFactory [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLog= gerFactory [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= s.SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 vers= ion 8.0.0 [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T07:22:02.7798= 16Z [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1631792 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-05-25T07:22:02.7798= 46Z [junit4] 2> 1631793 INFO (zkConnectionManagerCallback-7787-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631793 INFO (zkConnectionManagerCallback-7785-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631793 INFO (zkConnectionManagerCallback-7789-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631793 INFO (zkConnectionManagerCallback-7791-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631793 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1631793 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1631793 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1631793 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1631798 INFO (jetty-launcher-7780-thread-2) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:41117/solr [junit4] 2> 1631798 INFO (jetty-launcher-7780-thread-4) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:41117/solr [junit4] 2> 1631798 INFO (jetty-launcher-7780-thread-3) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:41117/solr [junit4] 2> 1631798 INFO (jetty-launcher-7780-thread-1) [ ] o.a.s.= c.ZkContainer Zookeeper client=3D127.0.0.1:41117/solr [junit4] 2> 1631799 INFO (zkConnectionManagerCallback-7807-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631799 INFO (zkConnectionManagerCallback-7801-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631799 INFO (zkConnectionManagerCallback-7803-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631799 INFO (zkConnectionManagerCallback-7805-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631799 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessio= nid 0x10018ada9b80005, likely client has closed socket [junit4] 2> 1631800 INFO (zkConnectionManagerCallback-7813-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631800 INFO (zkConnectionManagerCallback-7809-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631800 INFO (zkConnectionManagerCallback-7811-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631800 INFO (zkConnectionManagerCallback-7815-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631852 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1631852 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1631852 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1631852 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 1631853 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader= 127.0.0.1:42221_solr [junit4] 2> 1631853 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:39323_solr [junit4] 2> 1631853 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:42199_solr [junit4] 2> 1631853 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:45285_solr [junit4] 2> 1631853 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.Overseer Overseer (id=3D72084728193744905-127.0.0.1= :42221_solr-n_0000000000) starting [junit4] 2> 1631864 INFO (zkCallback-7808-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1631864 INFO (zkCallback-7812-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1631882 INFO (zkCallback-7814-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1631882 INFO (zkCallback-7810-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1631885 INFO (zkConnectionManagerCallback-7828-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1631886 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (3) [junit4] 2> 1631886 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0= .1:41117/solr ready [junit4] 2> 1631889 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient = cache for 2147483647 transient cores [junit4] 2> 1631889 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/li= ve_nodes/127.0.0.1:42221_solr [junit4] 2> 1631890 INFO (zkCallback-7808-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1631890 INFO (zkCallback-7810-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1631892 INFO (zkCallback-7814-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1631892 INFO (zkCallback-7812-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1631905 INFO (zkCallback-7827-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1632016 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42199.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@3d0adef1 [junit4] 2> 1632017 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39323.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@3d0adef1 [junit4] 2> 1632017 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42221.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@3d0adef1 [junit4] 2> 1632024 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42199.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@3d0adef1 [junit4] 2> 1632024 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42199.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@3d0adef1 [junit4] 2> 1632025 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42221.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@3d0adef1 [junit4] 2> 1632025 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42221.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@3d0adef1 [junit4] 2> 1632025 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39323.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@3d0adef1 [junit4] 2> 1632025 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39323.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@3d0adef1 [junit4] 2> 1632025 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623= E-001/tempDir-001/node2/. [junit4] 2> 1632025 INFO (jetty-launcher-7780-thread-4) [n:127.0.0.1:= 42221_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623= E-001/tempDir-001/node4/. [junit4] 2> 1632026 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623= E-001/tempDir-001/node1/. [junit4] 2> 1632028 INFO (zkConnectionManagerCallback-7833-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1632029 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (4) [junit4] 2> 1632030 INFO (zkConnectionManagerCallback-7838-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1632030 INFO (jetty-launcher-7780-thread-2) [n:127.0.0.1:= 42199_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0= .1:41117/solr ready [junit4] 2> 1632032 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (4) [junit4] 2> 1632032 INFO (jetty-launcher-7780-thread-1) [n:127.0.0.1:= 39323_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0= .1:41117/solr ready [junit4] 2> 1632109 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45285.so= lr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@3d0adef1 [junit4] 2> 1632114 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45285.so= lr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@3d0adef1 [junit4] 2> 1632114 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45285.so= lr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserve= r.JmxMBeanServer@3d0adef1 [junit4] 2> 1632115 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions unde= rneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623= E-001/tempDir-001/node3/. [junit4] 2> 1632117 INFO (zkConnectionManagerCallback-7843-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1632118 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper..= . (0) -> (4) [junit4] 2> 1632118 INFO (jetty-launcher-7780-thread-3) [n:127.0.0.1:= 45285_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0= .1:41117/solr ready [junit4] 2> 1632140 INFO (zkConnectionManagerCallback-7845-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1632142 INFO (zkConnectionManagerCallback-7850-thread-1) = [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1632143 INFO (SUITE-SearchRateTriggerTest-seed#[F6BD4087D= 0C1623E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Zoo= Keeper... (0) -> (4) [junit4] 2> 1632143 INFO (SUITE-SearchRateTriggerTest-seed#[F6BD4087D= 0C1623E]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at= 127.0.0.1:41117/solr ready [junit4] 2> 1632157 INFO (TEST-SearchRateTriggerTest.testTrigger-seed= #[F6BD4087D0C1623E]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from= ZooKeeper... (0) -> (4) [junit4] 2> 1632159 INFO (qtp84213518-23483) [n:127.0.0.1:39323_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with pa= rams replicationFactor=3D2&collection.configName=3Dconf&maxShardsPerNode=3D= 1&name=3DSearchRateTriggerTest-collection1&nrtReplicas=3D2&action=3DCREATE&= numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1632160 INFO (OverseerThreadFactory-8681-thread-1) [ ]= o.a.s.c.a.c.CreateCollectionCmd Create collection SearchRateTriggerTest-co= llection1 [junit4] 2> 1632264 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"SearchRateTriggerTest-collection1_shard1_replica= _n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:45285/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1632265 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"SearchRateTriggerTest-collection1_shard1_replica= _n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42199/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1632266 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"SearchRateTriggerTest-collection1_shard2_replica= _n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:39323/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1632266 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"SearchRateTriggerTest-collection1_shard2_replica= _n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42221/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1632469 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.h.a.CoreAdm= inOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_node7= &collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigger= Test-collection1_shard2_replica_n4&action=3DCREATE&numShards=3D2&collection= =3DSearchRateTriggerTest-collection1&shard=3Dshard2&wt=3Djavabin&version=3D= 2&replicaType=3DNRT [junit4] 2> 1632469 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.h.a.CoreAd= minOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_node= 3&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigge= rTest-collection1_shard1_replica_n1&action=3DCREATE&numShards=3D2&collectio= n=3DSearchRateTriggerTest-collection1&shard=3Dshard1&wt=3Djavabin&version= =3D2&replicaType=3DNRT [junit4] 2> 1632469 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.Transien= tSolrCoreCacheDefault Allocating transient cache for 2147483647 transient c= ores [junit4] 2> 1632469 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.Transient= SolrCoreCacheDefault Allocating transient cache for 2147483647 transient co= res [junit4] 2> 1632470 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.h.a.CoreAd= minOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_node= 8&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigge= rTest-collection1_shard2_replica_n6&action=3DCREATE&numShards=3D2&collectio= n=3DSearchRateTriggerTest-collection1&shard=3Dshard2&wt=3Djavabin&version= =3D2&replicaType=3DNRT [junit4] 2> 1632470 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.h.a.CoreA= dminOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_nod= e5&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigg= erTest-collection1_shard1_replica_n2&action=3DCREATE&numShards=3D2&collecti= on=3DSearchRateTriggerTest-collection1&shard=3Dshard1&wt=3Djavabin&version= =3D2&replicaType=3DNRT [junit4] 2> 1632470 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.Transie= ntSolrCoreCacheDefault Allocating transient cache for 2147483647 transient = cores [junit4] 2> 1633475 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene Mat= chVersion: 8.0.0 [junit4] 2> 1633475 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene Matc= hVersion: 8.0.0 [junit4] 2> 1633475 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene Matc= hVersion: 8.0.0 [junit4] 2> 1633476 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene Match= Version: 8.0.0 [junit4] 2> 1633484 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.s.IndexSchema [SearchRateTrig= gerTest-collection1_shard1_replica_n2] Schema name=3Dminimal [junit4] 2> 1633484 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.s.IndexSchema [SearchRateTrigg= erTest-collection1_shard2_replica_n6] Schema name=3Dminimal [junit4] 2> 1633485 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.s.IndexSchema [SearchRateTrigg= erTest-collection1_shard1_replica_n1] Schema name=3Dminimal [junit4] 2> 1633485 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.s.IndexSchema [SearchRateTrigge= rTest-collection1_shard2_replica_n4] Schema name=3Dminimal [junit4] 2> 1633485 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema m= inimal/1.1 with uniqueid field id [junit4] 2> 1633485 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.CoreContainer Creating Solr= Core 'SearchRateTriggerTest-collection1_shard1_replica_n2' using configurat= ion from collection SearchRateTriggerTest-collection1, trusted=3Dtrue [junit4] 2> 1633485 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema mi= nimal/1.1 with uniqueid field id [junit4] 2> 1633485 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrC= ore 'SearchRateTriggerTest-collection1_shard2_replica_n6' using configurati= on from collection SearchRateTriggerTest-collection1, trusted=3Dtrue [junit4] 2> 1633485 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monit= oring for 'solr_42199.solr.core.SearchRateTriggerTest-collection1.shard1.re= plica_n2' (registry 'solr.core.SearchRateTriggerTest-collection1.shard1.rep= lica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef= 1 [junit4] 2> 1633485 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monito= ring for 'solr_42221.solr.core.SearchRateTriggerTest-collection1.shard2.rep= lica_n6' (registry 'solr.core.SearchRateTriggerTest-collection1.shard2.repl= ica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef1 [junit4] 2> 1633485 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrat= egy.Builder [junit4] 2> 1633486 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema mi= nimal/1.1 with uniqueid field id [junit4] 2> 1633486 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrCore [[SearchRateTrigge= rTest-collection1_shard1_replica_n2] ] Opening new SolrCore at [/home/jenki= ns/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/sol= r.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/= node2/SearchRateTriggerTest-collection1_shard1_replica_n2], dataDir=3D[/hom= e/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/t= emp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempD= ir-001/node2/./SearchRateTriggerTest-collection1_shard1_replica_n2/data/] [junit4] 2> 1633486 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrate= gy.Builder [junit4] 2> 1633486 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrC= ore 'SearchRateTriggerTest-collection1_shard1_replica_n1' using configurati= on from collection SearchRateTriggerTest-collection1, trusted=3Dtrue [junit4] 2> 1633486 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.c.SolrCore [[SearchRateTrigger= Test-collection1_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkin= s/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr= .cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/n= ode4/SearchRateTriggerTest-collection1_shard2_replica_n6], dataDir=3D[/home= /jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/te= mp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDi= r-001/node4/./SearchRateTriggerTest-collection1_shard2_replica_n6/data/] [junit4] 2> 1633486 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monito= ring for 'solr_45285.solr.core.SearchRateTriggerTest-collection1.shard1.rep= lica_n1' (registry 'solr.core.SearchRateTriggerTest-collection1.shard1.repl= ica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef1 [junit4] 2> 1633486 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrate= gy.Builder [junit4] 2> 1633486 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.SolrCore [[SearchRateTrigger= Test-collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkin= s/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr= .cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/n= ode3/SearchRateTriggerTest-collection1_shard1_replica_n1], dataDir=3D[/home= /jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/te= mp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDi= r-001/node3/./SearchRateTriggerTest-collection1_shard1_replica_n1/data/] [junit4] 2> 1633486 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.s.IndexSchema Loaded schema min= imal/1.1 with uniqueid field id [junit4] 2> 1633486 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.CoreContainer Creating SolrCo= re 'SearchRateTriggerTest-collection1_shard2_replica_n4' using configuratio= n from collection SearchRateTriggerTest-collection1, trusted=3Dtrue [junit4] 2> 1633486 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitor= ing for 'solr_39323.solr.core.SearchRateTriggerTest-collection1.shard2.repl= ica_n4' (registry 'solr.core.SearchRateTriggerTest-collection1.shard2.repli= ca_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef1 [junit4] 2> 1633486 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrateg= y.Builder [junit4] 2> 1633486 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.SolrCore [[SearchRateTriggerT= est-collection1_shard2_replica_n4] ] Opening new SolrCore at [/home/jenkins= /workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.= cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/no= de1/SearchRateTriggerTest-collection1_shard2_replica_n4], dataDir=3D[/home/= jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/tem= p/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir= -001/node1/./SearchRateTriggerTest-collection1_shard2_replica_n4/data/] [junit4] 2> 1633528 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLo= g implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1633528 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing Updat= eLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNum= LogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1633534 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog= implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1633534 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.UpdateLog Initializing Update= Log: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumL= ogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1633534 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommi= t: disabled [junit4] 2> 1633534 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommi= t: disabled [junit4] 2> 1633534 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit= : disabled [junit4] 2> 1633534 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit= : disabled [junit4] 2> 1633535 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLo= g implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1633535 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.u.UpdateLog Initializing Updat= eLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNum= LogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1633535 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateL= og implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1633535 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.UpdateLog Initializing Upda= teLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNu= mLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1633536 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [S= earcher@6edb70c3[SearchRateTriggerTest-collection1_shard1_replica_n1] main] [junit4] 2> 1633536 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Se= archer@574d18d6[SearchRateTriggerTest-collection1_shard2_replica_n4] main] [junit4] 2> 1633536 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommi= t: disabled [junit4] 2> 1633536 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommi= t: disabled [junit4] 2> 1633536 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoComm= it: disabled [junit4] 2> 1633536 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoComm= it: disabled [junit4] 2> 1633536 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Confi= gured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1633536 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Config= ured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1633536 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loade= d null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/= conf [junit4] 2> 1633536 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Loaded= null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/c= onf [junit4] 2> 1633537 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits w= ill be reserved for 10000ms. [junit4] 2> 1633537 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.h.ReplicationHandler Commits wi= ll be reserved for 10000ms. [junit4] 2> 1633537 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [S= earcher@35ed6f7e[SearchRateTriggerTest-collection1_shard2_replica_n6] main] [junit4] 2> 1633537 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [= Searcher@2c99802f[SearchRateTriggerTest-collection1_shard1_replica_n2] main= ] [junit4] 2> 1633537 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Confi= gured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1633537 INFO (searcherExecutor-8693-thread-1-processing-n= :127.0.0.1:39323_solr x:SearchRateTriggerTest-collection1_shard2_replica_n4= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7) [n:127.0.0.1:39= 323_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:Search= RateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.SolrCore [SearchRate= TriggerTest-collection1_shard2_replica_n4] Registered new searcher Searcher= @574d18d6[SearchRateTriggerTest-collection1_shard2_replica_n4] main{Exitabl= eDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1633537 INFO (searcherExecutor-8692-thread-1-processing-n= :127.0.0.1:45285_solr x:SearchRateTriggerTest-collection1_shard1_replica_n1= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3) [n:127.0.0.1:45= 285_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:Search= RateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.SolrCore [SearchRate= TriggerTest-collection1_shard1_replica_n1] Registered new searcher Searcher= @6edb70c3[SearchRateTriggerTest-collection1_shard1_replica_n1] main{Exitabl= eDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1633537 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Conf= igured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1633537 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max= version in index or recent updates, using new clock 1601419791065677824 [junit4] 2> 1633537 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loade= d null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs/= conf [junit4] 2> 1633537 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.UpdateLog Could not find max = version in index or recent updates, using new clock 1601419791065677824 [junit4] 2> 1633538 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Load= ed null at path _rest_managed.json using ZooKeeperStorageIO:path=3D/configs= /conf [junit4] 2> 1633538 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits w= ill be reserved for 10000ms. [junit4] 2> 1633538 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits = will be reserved for 10000ms. [junit4] 2> 1633538 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.UpdateLog Could not find ma= x version in index or recent updates, using new clock 1601419791066726400 [junit4] 2> 1633538 INFO (searcherExecutor-8691-thread-1-processing-n= :127.0.0.1:42221_solr x:SearchRateTriggerTest-collection1_shard2_replica_n6= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8) [n:127.0.0.1:42= 221_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:Search= RateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.SolrCore [SearchRate= TriggerTest-collection1_shard2_replica_n6] Registered new searcher Searcher= @35ed6f7e[SearchRateTriggerTest-collection1_shard2_replica_n6] main{Exitabl= eDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1633538 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max= version in index or recent updates, using new clock 1601419791066726400 [junit4] 2> 1633540 INFO (searcherExecutor-8690-thread-1-processing-n= :127.0.0.1:42199_solr x:SearchRateTriggerTest-collection1_shard1_replica_n2= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5) [n:127.0.0.1:42= 199_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:Search= RateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrCore [SearchRate= TriggerTest-collection1_shard1_replica_n2] Registered new searcher Searcher= @2c99802f[SearchRateTriggerTest-collection1_shard1_replica_n2] main{Exitabl= eDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1633541 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ZkShardTerms Successful updat= e of terms at /collections/SearchRateTriggerTest-collection1/terms/shard2 t= o Terms{values=3D{core_node7=3D0}, version=3D0} [junit4] 2> 1633541 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful upd= ate of terms at /collections/SearchRateTriggerTest-collection1/terms/shard1= to Terms{values=3D{core_node5=3D0}, version=3D0} [junit4] 2> 1633542 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Failed to save = terms, version is not a match, retrying [junit4] 2> 1633542 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.c.ZkShardTerms Successful upda= te of terms at /collections/SearchRateTriggerTest-collection1/terms/shard2 = to Terms{values=3D{core_node7=3D0, core_node8=3D0}, version=3D1} [junit4] 2> 1633543 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful upda= te of terms at /collections/SearchRateTriggerTest-collection1/terms/shard1 = to Terms{values=3D{core_node3=3D0, core_node5=3D0}, version=3D1} [junit4] 2> 1633543 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext = Enough replicas found to continue. [junit4] 2> 1633543 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext = I may be the new leader - try and sync [junit4] 2> 1633543 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext En= ough replicas found to continue. [junit4] 2> 1633543 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.SyncStrategy Sync replicas = to http://127.0.0.1:42199/solr/SearchRateTriggerTest-collection1_shard1_rep= lica_n2/ [junit4] 2> 1633544 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext I = may be the new leader - try and sync [junit4] 2> 1633544 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.SyncStrategy Sync replicas to= http://127.0.0.1:39323/solr/SearchRateTriggerTest-collection1_shard2_repli= ca_n4/ [junit4] 2> 1633544 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.PeerSync PeerSync: core=3DSea= rchRateTriggerTest-collection1_shard2_replica_n4 url=3Dhttp://127.0.0.1:393= 23/solr START replicas=3D[http://127.0.0.1:42221/solr/SearchRateTriggerTest= -collection1_shard2_replica_n6/] nUpdates=3D100 [junit4] 2> 1633544 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=3DS= earchRateTriggerTest-collection1_shard1_replica_n2 url=3Dhttp://127.0.0.1:4= 2199/solr START replicas=3D[http://127.0.0.1:45285/solr/SearchRateTriggerTe= st-collection1_shard1_replica_n1/] nUpdates=3D100 [junit4] 2> 1633549 INFO (qtp810516989-23464) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.c.S.Request [SearchRateTrigger= Test-collection1_shard2_replica_n6] webapp=3D/solr path=3D/get params=3D{d= istrib=3Dfalse&qt=3D/get&fingerprint=3Dfalse&getVersions=3D100&wt=3Djavabin= &version=3D2} status=3D0 QTime=3D4 [junit4] 2> 1633549 INFO (qtp674343881-23460) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.c.S.Request [SearchRateTrigger= Test-collection1_shard1_replica_n1] webapp=3D/solr path=3D/get params=3D{d= istrib=3Dfalse&qt=3D/get&fingerprint=3Dfalse&getVersions=3D100&wt=3Djavabin= &version=3D2} status=3D0 QTime=3D0 [junit4] 2> 1633844 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.u.PeerSync PeerSync: core=3DSea= rchRateTriggerTest-collection1_shard2_replica_n4 url=3Dhttp://127.0.0.1:393= 23/solr DONE. We have no versions. sync failed. [junit4] 2> 1633844 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.SyncStrategy Leader's attempt= to sync with shard failed, moving to the next candidate [junit4] 2> 1633844 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext We= failed sync, but we have no versions - we can't sync in that case - we wer= e active before, so become leader anyway [junit4] 2> 1633844 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext Fo= und all replicas participating in election, clear LIR [junit4] 2> 1633844 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.u.PeerSync PeerSync: core=3DS= earchRateTriggerTest-collection1_shard1_replica_n2 url=3Dhttp://127.0.0.1:4= 2199/solr DONE. We have no versions. sync failed. [junit4] 2> 1633844 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.SyncStrategy Leader's attem= pt to sync with shard failed, moving to the next candidate [junit4] 2> 1633844 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext = We failed sync, but we have no versions - we can't sync in that case - we w= ere active before, so become leader anyway [junit4] 2> 1633844 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext = Found all replicas participating in election, clear LIR [junit4] 2> 1633846 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ShardLeaderElectionContext = I am the new leader: http://127.0.0.1:42199/solr/SearchRateTriggerTest-coll= ection1_shard1_replica_n2/ shard1 [junit4] 2> 1633846 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ShardLeaderElectionContext I = am the new leader: http://127.0.0.1:39323/solr/SearchRateTriggerTest-collec= tion1_shard2_replica_n4/ shard2 [junit4] 2> 1633947 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.c.ZkController I am the leader,= no recovery necessary [junit4] 2> 1633947 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.c.ZkController I am the leade= r, no recovery necessary [junit4] 2> 1633949 INFO (qtp84213518-23457) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTrigg= erTest-collection1_shard2_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&coreNodeName=3Dcore= _node7&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateT= riggerTest-collection1_shard2_replica_n4&action=3DCREATE&numShards=3D2&coll= ection=3DSearchRateTriggerTest-collection1&shard=3Dshard2&wt=3Djavabin&vers= ion=3D2&replicaType=3DNRT} status=3D0 QTime=3D1480 [junit4] 2> 1633954 INFO (qtp1539286898-23471) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTri= ggerTest-collection1_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&coreNodeName=3Dcore= _node5&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateT= riggerTest-collection1_shard1_replica_n2&action=3DCREATE&numShards=3D2&coll= ection=3DSearchRateTriggerTest-collection1&shard=3Dshard1&wt=3Djavabin&vers= ion=3D2&replicaType=3DNRT} status=3D0 QTime=3D1483 [junit4] 2> 1634050 INFO (zkCallback-7808-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state= .json] for collection [SearchRateTriggerTest-collection1] has occurred - up= dating... (live nodes size: [4]) [junit4] 2> 1634050 INFO (zkCallback-7810-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state= .json] for collection [SearchRateTriggerTest-collection1] has occurred - up= dating... (live nodes size: [4]) [junit4] 2> 1634646 INFO (zkCallback-7814-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state= .json] for collection [SearchRateTriggerTest-collection1] has occurred - up= dating... (live nodes size: [4]) [junit4] 2> 1634646 INFO (zkCallback-7810-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state= .json] for collection [SearchRateTriggerTest-collection1] has occurred - up= dating... (live nodes size: [4]) [junit4] 2> 1634646 INFO (zkCallback-7808-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state= .json] for collection [SearchRateTriggerTest-collection1] has occurred - up= dating... (live nodes size: [4]) [junit4] 2> 1634646 INFO (zkCallback-7812-thread-1) [ ] o.a.s.c.c.= ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected typ= e:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state= .json] for collection [SearchRateTriggerTest-collection1] has occurred - up= dating... (live nodes size: [4]) [junit4] 2> 1634787 INFO (qtp810516989-23478) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection1_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&coreNodeName=3Dcore= _node8&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateT= riggerTest-collection1_shard2_replica_n6&action=3DCREATE&numShards=3D2&coll= ection=3DSearchRateTriggerTest-collection1&shard=3Dshard2&wt=3Djavabin&vers= ion=3D2&replicaType=3DNRT} status=3D0 QTime=3D2317 [junit4] 2> 1634787 INFO (qtp674343881-23482) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTrig= gerTest-collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{qt=3D/admin/cores&coreNodeName=3Dcore= _node3&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateT= riggerTest-collection1_shard1_replica_n1&action=3DCREATE&numShards=3D2&coll= ection=3DSearchRateTriggerTest-collection1&shard=3Dshard1&wt=3Djavabin&vers= ion=3D2&replicaType=3DNRT} status=3D0 QTime=3D2317 [junit4] 2> 1634788 INFO (qtp84213518-23483) [n:127.0.0.1:39323_solr = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for = at most 30 seconds. Check all shard replicas [junit4] 2> 1634788 INFO (qtp84213518-23483) [n:127.0.0.1:39323_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections p= arams=3D{replicationFactor=3D2&collection.configName=3Dconf&maxShardsPerNod= e=3D1&name=3DSearchRateTriggerTest-collection1&nrtReplicas=3D2&action=3DCRE= ATE&numShards=3D2&wt=3Djavabin&version=3D2} status=3D0 QTime=3D2629 [junit4] 2> 1634789 INFO (qtp84213518-23453) [n:127.0.0.1:39323_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with pa= rams replicationFactor=3D2&collection.configName=3Dconf&maxShardsPerNode=3D= 1&name=3DSearchRateTriggerTest-collection2&nrtReplicas=3D2&action=3DCREATE&= numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 1634790 INFO (OverseerThreadFactory-8681-thread-2) [ ]= o.a.s.c.a.c.CreateCollectionCmd Create collection SearchRateTriggerTest-co= llection2 [junit4] 2> 1634790 INFO (OverseerCollectionConfigSetProcessor-720847= 28193744905-127.0.0.1:42221_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQ= ueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn= 't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 1634994 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection2", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"SearchRateTriggerTest-collection2_shard1_replica= _n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:39323/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1634994 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection2", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"SearchRateTriggerTest-collection2_shard1_replica= _n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42221/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1634995 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection2", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"SearchRateTriggerTest-collection2_shard2_replica= _n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:42199/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1634996 INFO (OverseerStateUpdate-72084728193744905-127.0= .0.1:42221_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica()= { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"SearchRateTriggerTest-collection2", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"SearchRateTriggerTest-collection2_shard2_replica= _n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:45285/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 1635197 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.h.a.CoreAd= minOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_node= 5&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigge= rTest-collection2_shard1_replica_n2&action=3DCREATE&numShards=3D2&collectio= n=3DSearchRateTriggerTest-collection2&shard=3Dshard1&wt=3Djavabin&version= =3D2&replicaType=3DNRT [junit4] 2> 1635197 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = x:SearchRateTriggerTest-collection2_shard1_replica_n1] o.a.s.h.a.CoreAdm= inOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_node3= &collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigger= Test-collection2_shard1_replica_n1&action=3DCREATE&numShards=3D2&collection= =3DSearchRateTriggerTest-collection2&shard=3Dshard1&wt=3Djavabin&version=3D= 2&replicaType=3DNRT [junit4] 2> 1635197 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.h.a.CoreAd= minOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_node= 8&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigge= rTest-collection2_shard2_replica_n6&action=3DCREATE&numShards=3D2&collectio= n=3DSearchRateTriggerTest-collection2&shard=3Dshard2&wt=3Djavabin&version= =3D2&replicaType=3DNRT [junit4] 2> 1635198 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r x:SearchRateTriggerTest-collection2_shard2_replica_n4] o.a.s.h.a.CoreA= dminOperation core create command qt=3D/admin/cores&coreNodeName=3Dcore_nod= e7&collection.configName=3Dconf&newCollection=3Dtrue&name=3DSearchRateTrigg= erTest-collection2_shard2_replica_n4&action=3DCREATE&numShards=3D2&collecti= on=3DSearchRateTriggerTest-collection2&shard=3Dshard2&wt=3Djavabin&version= =3D2&replicaType=3DNRT [junit4] 2> 1636203 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene Matc= hVersion: 8.0.0 [junit4] 2> 1636203 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene Mat= chVersion: 8.0.0 [junit4] 2> 1636203 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene Match= Version: 8.0.0 [junit4] 2> 1636206 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene Matc= hVersion: 8.0.0 [junit4] 2> 1636208 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.s.IndexSchema [SearchRateTrigge= rTest-collection2_shard1_replica_n1] Schema name=3Dminimal [junit4] 2> 1636208 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.s.IndexSchema [SearchRateTrigg= erTest-collection2_shard1_replica_n2] Schema name=3Dminimal [junit4] 2> 1636208 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.s.IndexSchema [SearchRateTrig= gerTest-collection2_shard2_replica_n4] Schema name=3Dminimal [junit4] 2> 1636209 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.s.IndexSchema Loaded schema m= inimal/1.1 with uniqueid field id [junit4] 2> 1636209 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema mi= nimal/1.1 with uniqueid field id [junit4] 2> 1636209 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema min= imal/1.1 with uniqueid field id [junit4] 2> 1636209 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrC= ore 'SearchRateTriggerTest-collection2_shard1_replica_n2' using configurati= on from collection SearchRateTriggerTest-collection2, trusted=3Dtrue [junit4] 2> 1636209 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.c.CoreContainer Creating Solr= Core 'SearchRateTriggerTest-collection2_shard2_replica_n4' using configurat= ion from collection SearchRateTriggerTest-collection2, trusted=3Dtrue [junit4] 2> 1636209 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCo= re 'SearchRateTriggerTest-collection2_shard1_replica_n1' using configuratio= n from collection SearchRateTriggerTest-collection2, trusted=3Dtrue [junit4] 2> 1636209 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitor= ing for 'solr_39323.solr.core.SearchRateTriggerTest-collection2.shard1.repl= ica_n1' (registry 'solr.core.SearchRateTriggerTest-collection2.shard1.repli= ca_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef1 [junit4] 2> 1636209 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monit= oring for 'solr_42199.solr.core.SearchRateTriggerTest-collection2.shard2.re= plica_n4' (registry 'solr.core.SearchRateTriggerTest-collection2.shard2.rep= lica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef= 1 [junit4] 2> 1636209 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monito= ring for 'solr_42221.solr.core.SearchRateTriggerTest-collection2.shard1.rep= lica_n2' (registry 'solr.core.SearchRateTriggerTest-collection2.shard1.repl= ica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef1 [junit4] 2> 1636209 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrateg= y.Builder [junit4] 2> 1636209 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.c.SolrCore solr.RecoveryStrat= egy.Builder [junit4] 2> 1636210 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrate= gy.Builder [junit4] 2> 1636210 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.c.SolrCore [[SearchRateTrigge= rTest-collection2_shard2_replica_n4] ] Opening new SolrCore at [/home/jenki= ns/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/sol= r.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/= node2/SearchRateTriggerTest-collection2_shard2_replica_n4], dataDir=3D[/hom= e/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/t= emp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempD= ir-001/node2/./SearchRateTriggerTest-collection2_shard2_replica_n4/data/] [junit4] 2> 1636210 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.c.SolrCore [[SearchRateTriggerT= est-collection2_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins= /workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.= cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/no= de1/SearchRateTriggerTest-collection2_shard1_replica_n1], dataDir=3D[/home/= jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/tem= p/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir= -001/node1/./SearchRateTriggerTest-collection2_shard1_replica_n1/data/] [junit4] 2> 1636210 INFO (qtp810516989-23462) [n:127.0.0.1:42221_solr= c:SearchRateTriggerTest-collection2 s:shard1 r:core_node5 x:SearchRateTrig= gerTest-collection2_shard1_replica_n2] o.a.s.c.SolrCore [[SearchRateTrigger= Test-collection2_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkin= s/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr= .cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/n= ode4/SearchRateTriggerTest-collection2_shard1_replica_n2], dataDir=3D[/home= /jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/te= mp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDi= r-001/node4/./SearchRateTriggerTest-collection2_shard1_replica_n2/data/] [junit4] 2> 1636210 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.s.IndexSchema [SearchRateTrigg= erTest-collection2_shard2_replica_n6] Schema name=3Dminimal [junit4] 2> 1636221 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema mi= nimal/1.1 with uniqueid field id [junit4] 2> 1636221 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrC= ore 'SearchRateTriggerTest-collection2_shard2_replica_n6' using configurati= on from collection SearchRateTriggerTest-collection2, trusted=3Dtrue [junit4] 2> 1636221 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monito= ring for 'solr_45285.solr.core.SearchRateTriggerTest-collection2.shard2.rep= lica_n6' (registry 'solr.core.SearchRateTriggerTest-collection2.shard2.repl= ica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3d0adef1 [junit4] 2> 1636221 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrate= gy.Builder [junit4] 2> 1636221 INFO (qtp674343881-23455) [n:127.0.0.1:45285_solr= c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTrig= gerTest-collection2_shard2_replica_n6] o.a.s.c.SolrCore [[SearchRateTrigger= Test-collection2_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkin= s/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr= .cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDir-001/n= ode3/SearchRateTriggerTest-collection2_shard2_replica_n6], dataDir=3D[/home= /jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/te= mp/solr.cloud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001/tempDi= r-001/node3/./SearchRateTriggerTest-collection2_shard2_replica_n6/data/] [junit4] 2> 1636259 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog= implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1636259 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.u.UpdateLog Initializing Update= Log: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumL= ogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1636260 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit= : disabled [junit4] 2> 1636260 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit= : disabled [junit4] 2> 1636262 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Se= archer@538cd02c[SearchRateTriggerTest-collection2_shard1_replica_n1] main] [junit4] 2> 1636263 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.u.UpdateHandler Using UpdateL= og implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1636263 INFO (qtp1539286898-23454) [n:127.0.0.1:42199_sol= r c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTri= ggerTest-collection2_shard2_replica_n4] o.a.s.u.UpdateLog Initializing Upda= teLog: dataDir=3Dnull defaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNu= mLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1636263 INFO (qtp84213518-23479) [n:127.0.0.1:39323_solr = c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTrigg= erTest-collection2_shard1_replica_n1] o.a.s.r.Ma [...truncated too long message...] thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.sol= r.metrics.reporters.SolrJmxReporter@2d4ac3ab: rootName =3D solr_43347, doma= in =3D solr.jetty, service url =3D null, agent id =3D null] for registry so= lr.jetty / com.codahale.metrics.MetricRegistry@926363b [junit4] 2> 158342 INFO (jetty-closer-94-thread-3) [ ] o.a.s.m.Sol= rMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag=3Dnu= ll [junit4] 2> 158342 INFO (jetty-closer-94-thread-3) [ ] o.a.s.m.r.S= olrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxR= eporter@23732f8b: rootName =3D solr_46667, domain =3D solr.jetty, service u= rl =3D null, agent id =3D null] for registry solr.jetty / com.codahale.metr= ics.MetricRegistry@926363b [junit4] 2> 158343 INFO (jetty-closer-94-thread-1) [ ] o.e.j.s.h.C= ontextHandler Stopped o.e.j.s.ServletContextHandler@7762e6ba{/solr,null,UNA= VAILABLE} [junit4] 2> 158343 INFO (jetty-closer-94-thread-1) [ ] o.e.j.s.ses= sion node0 Stopped scavenging [junit4] 2> 158343 INFO (jetty-closer-94-thread-2) [ ] o.a.s.c.ZkC= ontroller Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:43347_solr [junit4] 2> 158344 INFO (jetty-closer-94-thread-3) [ ] o.a.s.c.ZkC= ontroller Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46667_solr [junit4] 2> 158344 INFO (jetty-closer-94-thread-4) [ ] o.a.s.m.Sol= rMetricManager Closing metric reporters for registry=3Dsolr.jetty, tag=3Dnu= ll [junit4] 2> 158344 INFO (jetty-closer-94-thread-4) [ ] o.a.s.m.r.S= olrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxR= eporter@61707895: rootName =3D solr_44519, domain =3D solr.jetty, service u= rl =3D null, agent id =3D null] for registry solr.jetty / com.codahale.metr= ics.MetricRegistry@926363b [junit4] 2> 158344 INFO (jetty-closer-94-thread-2) [ ] o.a.s.m.Sol= rMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag=3D= null [junit4] 2> 158344 INFO (zkCallback-155-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (1) [junit4] 2> 158344 INFO (zkCallback-138-thread-2) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (1) [junit4] 2> 158345 INFO (zkCallback-151-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (1) [junit4] 2> 158345 INFO (zkCallback-125-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (1) [junit4] 2> 158345 INFO (zkCallback-123-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (1) [junit4] 2> 158345 INFO (zkCallback-121-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (1) [junit4] 2> 158345 INFO (jetty-closer-94-thread-3) [ ] o.a.s.m.Sol= rMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag=3D= null [junit4] 2> 158346 INFO (jetty-closer-94-thread-2) [ ] o.a.s.c.Ove= rseer Overseer (id=3D72084799614615562-127.0.0.1:43347_solr-n_0000000000) c= losing [junit4] 2> 158347 INFO (OverseerStateUpdate-72084799614615562-127.0.= 0.1:43347_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting = : 127.0.0.1:43347_solr [junit4] 2> 158347 WARN (OverseerAutoScalingTriggerThread-72084799614= 615562-127.0.0.1:43347_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerT= hread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 158348 INFO (jetty-closer-94-thread-3) [ ] o.e.j.s.h.C= ontextHandler Stopped o.e.j.s.ServletContextHandler@13249abb{/solr,null,UNA= VAILABLE} [junit4] 2> 158348 INFO (jetty-closer-94-thread-3) [ ] o.e.j.s.ses= sion node0 Stopped scavenging [junit4] 2> 158350 INFO (jetty-closer-94-thread-4) [ ] o.a.s.c.ZkC= ontroller Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44519_solr [junit4] 2> 158350 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 0x10018be4abc000a, likely client has closed socket [junit4] 2> 158350 INFO (zkCallback-155-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 158350 INFO (jetty-closer-94-thread-4) [ ] o.a.s.m.Sol= rMetricManager Closing metric reporters for registry=3Dsolr.cluster, tag=3D= null [junit4] 2> 158350 INFO (zkCallback-125-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 158350 INFO (zkCallback-125-thread-2) [ ] o.a.s.c.Over= seerElectionContext I am going to be the leader 127.0.0.1:44519_solr [junit4] 2> 158352 INFO (jetty-closer-94-thread-4) [ ] o.e.j.s.h.C= ontextHandler Stopped o.e.j.s.ServletContextHandler@675c5e72{/solr,null,UNA= VAILABLE} [junit4] 2> 158353 INFO (jetty-closer-94-thread-4) [ ] o.e.j.s.ses= sion node0 Stopped scavenging [junit4] 2> 159850 WARN (zkCallback-121-thread-1) [ ] o.a.s.c.c.Zk= StateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperE= rrorCode =3D Session expired for /live_nodes] [junit4] 2> 159851 INFO (jetty-closer-94-thread-2) [ ] o.e.j.s.h.C= ontextHandler Stopped o.e.j.s.ServletContextHandler@13043e15{/solr,null,UNA= VAILABLE} [junit4] 2> 159851 INFO (jetty-closer-94-thread-2) [ ] o.e.j.s.ses= sion node0 Stopped scavenging [junit4] 2> 159852 ERROR (SUITE-SearchRateTriggerTest-seed#[F6BD4087D0= C1623E]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not reg= istered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN ser= ver state changes [junit4] 2> 159852 INFO (SUITE-SearchRateTriggerTest-seed#[F6BD4087D0= C1623E]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42431 4= 2431 [junit4] 2> 160807 INFO (Thread-65) [ ] o.a.s.c.ZkTestServer conne= cting to 127.0.0.1:42431 42431 [junit4] 2> 160808 WARN (Thread-65) [ ] o.a.s.c.ZkTestServer Watch= limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =0915=09/solr/collections/SearchRateTriggerTest-collection= 1/terms/shard1 [junit4] 2> =0915=09/solr/collections/SearchRateTriggerTest-collection= 1/terms/shard2 [junit4] 2> =0912=09/solr/clusterprops.json [junit4] 2> =0912=09/solr/aliases.json [junit4] 2> =098=09/solr/configs/conf [junit4] 2> =098=09/solr/collections/SearchRateTriggerTest-collection2= /terms/shard2 [junit4] 2> =098=09/solr/collections/SearchRateTriggerTest-collection2= /terms/shard1 [junit4] 2> =094=09/solr/security.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =0912=09/solr/clusterstate.json [junit4] 2> =098=09/solr/collections/SearchRateTriggerTest-collection1= /state.json [junit4] 2> =094=09/solr/collections/SearchRateTriggerTest-collection2= /state.json [junit4] 2> =092=09/solr/overseer_elect/election/72084799614615562-127= .0.0.1:43347_solr-n_0000000000 [junit4] 2> =092=09/solr/overseer_elect/election/72084799614615563-127= .0.0.1:46667_solr-n_0000000001 [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =0923=09/solr/collections [junit4] 2> =0912=09/solr/live_nodes [junit4] 2>=20 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.clo= ud.autoscaling.SearchRateTriggerTest_F6BD4087D0C1623E-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene70): {}, do= cValues:{}, maxPointsInLeafNode=3D1148, maxMBSortInHeap=3D6.216331458588025= , sim=3DAsserting(org.apache.lucene.search.similarities.AssertingSimilarity= @6f3ab5f5), locale=3Den-BE, timezone=3DPacific/Niue [junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 10 = (64-bit)/cpus=3D8,threads=3D1,free=3D302969336,total=3D519569408 [junit4] 2> NOTE: All tests run in this JVM: [SearchRateTriggerTest, S= earchRateTriggerTest] [junit4] Completed [4/5 (1!)] on J1 in 51.89s, 3 tests, 1 failure <<< FA= ILURES! [...truncated 18 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:15= 68: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:10= 92: There were test failures: 5 suites, 15 tests, 1 failure [seed: F6BD4087= D0C1623E] Total time: 3 minutes 20 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 1/5 failed: org.apache.solr.cloud.autoscaling.SearchRateTriggerTe= st [repro] Exiting with code 256 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac= ) Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 Using GitBlamer to create author and commit information for al= l warnings. GIT_COMMIT=3Dc93f6283176070b9cc036faec919ef28290f5c4a, workspa= ce=3D/var/lib/jenkins/workspace/Lucene-Solr-master-Linux [WARNINGS] Computing warning deltas based on reference build #22098 Recording test results Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 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) Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 Setting ANT_1_8_2_HOME=3D/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstall= ation/ANT_1.8.2 ------=_Part_20_1308342125.1527234370680 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_20_1308342125.1527234370680--