From dev-return-312443-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Thu Feb 22 06:32:58 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 41CAC18064E for ; Thu, 22 Feb 2018 06:32:56 +0100 (CET) Received: (qmail 42655 invoked by uid 500); 22 Feb 2018 05:32:54 -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 42641 invoked by uid 99); 22 Feb 2018 05:32:54 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 22 Feb 2018 05:32:54 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id 7FB201A06EE for ; Thu, 22 Feb 2018 05:32:53 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 0.691 X-Spam-Level: X-Spam-Status: No, score=0.691 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, KAM_LAZY_DOMAIN_SECURITY=1, RCVD_IN_DNSWL_MED=-2.3, T_RP_MATCHES_RCVD=-0.01, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id mhGM0AsIiCfq for ; Thu, 22 Feb 2018 05:32:38 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTP id 80C095F5B3 for ; Thu, 22 Feb 2018 05:32:37 +0000 (UTC) Received: from jenkins-master.apache.org (unknown [62.210.60.235]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id 75F58E00D6 for ; Thu, 22 Feb 2018 05:32:36 +0000 (UTC) Received: from jenkins-master.apache.org (localhost [127.0.0.1]) by jenkins-master.apache.org (ASF Mail Server at jenkins-master.apache.org) with ESMTP id B4094A0130 for ; Thu, 22 Feb 2018 05:32:32 +0000 (UTC) Date: Thu, 22 Feb 2018 05:28:34 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <1678837583.96.1519277552739.JavaMail.jenkins@jenkins-master.apache.org> In-Reply-To: <1707316240.705.1519223857048.JavaMail.jenkins@jenkins-master.apache.org> References: <1707316240.705.1519223857048.JavaMail.jenkins@jenkins-master.apache.org> Subject: [JENKINS] Lucene-Solr-NightlyTests-7.x - Build # 155 - Still Unstable MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_95_683276157.1519277551011" X-Jenkins-Job: Lucene-Solr-NightlyTests-7.x X-Jenkins-Result: UNSTABLE ------=_Part_95_683276157.1519277551011 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.x/155/ 6 tests failed. FAILED: org.apache.lucene.analysis.core.TestRandomChains.testRandomChains Error Message: startOffset must be non-negative, and endOffset must be >=3D startOffset, a= nd offsets must not go backwards startOffset=3D6,endOffset=3D15,lastStartOf= fset=3D7 for field 'dummy' Stack Trace: java.lang.IllegalArgumentException: startOffset must be non-negative, and e= ndOffset must be >=3D startOffset, and offsets must not go backwards startO= ffset=3D6,endOffset=3D15,lastStartOffset=3D7 for field 'dummy' =09at __randomizedtesting.SeedInfo.seed([540F1928645813E:38A1D8F3C1579CFE]:= 0) =09at org.apache.lucene.index.DefaultIndexingChain$PerField.invert(DefaultI= ndexingChain.java:767) =09at org.apache.lucene.index.DefaultIndexingChain.processField(DefaultInde= xingChain.java:430) =09at org.apache.lucene.index.DefaultIndexingChain.processDocument(DefaultI= ndexingChain.java:392) =09at org.apache.lucene.index.DocumentsWriterPerThread.updateDocument(Docum= entsWriterPerThread.java:240) =09at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWrite= r.java:497) =09at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1= 729) =09at org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1464= ) =09at org.apache.lucene.index.RandomIndexWriter.addDocument(RandomIndexWrit= er.java:171) =09at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(Ba= seTokenStreamTestCase.java:672) =09at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(Ba= seTokenStreamTestCase.java:562) =09at org.apache.lucene.analysis.core.TestRandomChains.testRandomChains(Tes= tRandomChains.java:856) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at 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 org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsBasicDist= ributedZkTest Error Message: 3 threads leaked from SUITE scope at org.apache.solr.cloud.hdfs.HdfsBasicDi= stributedZkTest: 1) Thread[id=3D48716, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] at java.= lang.Thread.sleep(Native Method) at org.apache.http.impl.client.Idl= eConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lan= g.Thread.run(Thread.java:748) 2) Thread[id=3D48724, name=3DConnection ev= ictor, state=3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] = at java.lang.Thread.sleep(Native Method) at org.apache.http.imp= l.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) = at java.lang.Thread.run(Thread.java:748) 3) Thread[id=3D48734, name=3DC= onnection evictor, state=3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributed= ZkTest] at java.lang.Thread.sleep(Native Method) at org.apa= che.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java= :66) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 3 threads leaked from S= UITE scope at org.apache.solr.cloud.hdfs.HdfsBasicDistributedZkTest:=20 1) Thread[id=3D48716, name=3DConnection evictor, state=3DTIMED_WAITING, = group=3DTGRP-HdfsBasicDistributedZkTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConn= ectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=3D48724, name=3DConnection evictor, state=3DTIMED_WAITING, = group=3DTGRP-HdfsBasicDistributedZkTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConn= ectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 3) Thread[id=3D48734, name=3DConnection evictor, state=3DTIMED_WAITING, = group=3DTGRP-HdfsBasicDistributedZkTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConn= ectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) =09at __randomizedtesting.SeedInfo.seed([F982B7BEA7225924]:0) FAILED: org.apache.solr.cloud.TestUtilizeNode.test Error Message: no replica should be present in 127.0.0.1:44848_solr Stack Trace: java.lang.AssertionError: no replica should be present in 127.0.0.1:44848_= solr =09at __randomizedtesting.SeedInfo.seed([F982B7BEA7225924:71D6886409DE34DC]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.TestUtilizeNode.test(TestUtilizeNode.java:99) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEvent= Queue Error Message: action wasn't interrupted Stack Trace: java.lang.AssertionError: action wasn't interrupted =09at __randomizedtesting.SeedInfo.seed([F982B7BEA7225924:3037F510AE459FD1]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.assertTrue(Assert.java:43) =09at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQue= ue(TriggerIntegrationTest.java:726) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.hdfs.HdfsBasicDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:47288/_v/y: ADDREPLICA failed to crea= te replica Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:47288/_v/y: ADDREPLICA failed to create re= plica =09at __randomizedtesting.SeedInfo.seed([F982B7BEA7225924:71D6886409DE34DC]= :0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:643) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:255) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClie= nt.java:244) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSo= lrClient.java:483) =09at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolr= Client.java:413) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSo= lrClient.java:1104) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnS= taleState(CloudSolrClient.java:884) =09at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrCl= ient.java:817) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194= ) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211= ) =09at org.apache.solr.cloud.BasicDistributedZkTest.testANewCollectionInOneI= nstanceWithManualShardAssignement(BasicDistributedZkTest.java:802) =09at org.apache.solr.cloud.BasicDistributedZkTest.test(BasicDistributedZkT= est.java:375) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:993) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:968) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfte= rCoreReload Error Message: expected:<[{indexVersion=3D1519274070041,generation=3D2,filelist=3D[_0.cfe,= _0.cfs, _0.si, _1.cfe, _1.cfs, _1.si, _2.cfe, _2.cfs, _2.si, _3.cfe, _3.cf= s, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si, segments_2]}]> but = was:<[{indexVersion=3D1519274070041,generation=3D2,filelist=3D[_0.cfe, _0.c= fs, _0.si, _1.cfe, _1.cfs, _1.si, _2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3= .si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si, segments_2]}, {indexVers= ion=3D1519274070041,generation=3D3,filelist=3D[_6.cfe, _6.cfs, _6.si, segme= nts_3]}]> Stack Trace: java.lang.AssertionError: expected:<[{indexVersion=3D1519274070041,generati= on=3D2,filelist=3D[_0.cfe, _0.cfs, _0.si, _1.cfe, _1.cfs, _1.si, _2.cfe, _2= .cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, = _5.si, segments_2]}]> but was:<[{indexVersion=3D1519274070041,generation=3D= 2,filelist=3D[_0.cfe, _0.cfs, _0.si, _1.cfe, _1.cfs, _1.si, _2.cfe, _2.cfs,= _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si= , segments_2]}, {indexVersion=3D1519274070041,generation=3D3,filelist=3D[_6= .cfe, _6.cfs, _6.si, segments_3]}]> =09at __randomizedtesting.SeedInfo.seed([F982B7BEA7225924:DC55AC8ED76A5727]= :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:147) =09at org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCo= reReload(TestReplicationHandler.java:1277) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1737) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:934) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:970) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:984) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:817) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:468) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:829) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:879) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:890) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 3306 lines...] [junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains [junit4] 2> TEST FAIL: useCharFilter=3Dfalse text=3D''P\u2e2b\u03cb' [junit4] 2> Exception from random analyzer:=20 [junit4] 2> charfilters=3D [junit4] 2> org.apache.lucene.analysis.pattern.PatternReplaceCharFil= ter(a, , java.io.StringReader@7395911) [junit4] 2> tokenizer=3D [junit4] 2> org.apache.lucene.analysis.standard.UAX29URLEmailTokeniz= er(org.apache.lucene.util.AttributeFactory$DefaultAttributeFactory@30a9d9d4= ) [junit4] 2> filters=3D [junit4] 2> org.apache.lucene.analysis.miscellaneous.SetKeywordMarke= rFilter(ValidatingTokenFilter@22fbb52c term=3D,bytes=3D[],startOffset=3D0,e= ndOffset=3D0,positionIncrement=3D1,type=3Dword,keyword=3Dfalse, [gnk]) [junit4] 2> org.apache.lucene.analysis.shingle.ShingleFilter(Validat= ingTokenFilter@15c81f91 term=3D,bytes=3D[],startOffset=3D0,endOffset=3D0,po= sitionIncrement=3D1,type=3Dword,keyword=3Dfalse,positionLength=3D1, ) [junit4] 2> org.apache.lucene.analysis.cjk.CJKBigramFilter(Validatin= gTokenFilter@760925d4 term=3D,bytes=3D[],startOffset=3D0,endOffset=3D0,posi= tionIncrement=3D1,type=3Dword,keyword=3Dfalse,positionLength=3D1) [junit4] 2> offsetsAreCorrect=3Dfalse [junit4] 2> NOTE: download the large Jenkins line-docs file by running= 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DTestRandomCha= ins -Dtests.method=3DtestRandomChains -Dtests.seed=3D540F1928645813E -Dtest= s.multiplier=3D2 -Dtests.nightly=3Dtrue -Dtests.slow=3Dtrue -Dtests.linedoc= sfile=3D/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/= test-data/enwiki.random.lines.txt -Dtests.locale=3Des-BO -Dtests.timezone= =3DAsia/Karachi -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DISO-8859-1 [junit4] ERROR 82.1s J1 | TestRandomChains.testRandomChains <<< [junit4] > Throwable #1: java.lang.IllegalArgumentException: startOff= set must be non-negative, and endOffset must be >=3D startOffset, and offse= ts must not go backwards startOffset=3D6,endOffset=3D15,lastStartOffset=3D7= for field 'dummy' [junit4] > =09at __randomizedtesting.SeedInfo.seed([540F1928645813E:3= 8A1D8F3C1579CFE]:0) [junit4] > =09at org.apache.lucene.index.DefaultIndexingChain$PerFiel= d.invert(DefaultIndexingChain.java:767) [junit4] > =09at org.apache.lucene.index.DefaultIndexingChain.process= Field(DefaultIndexingChain.java:430) [junit4] > =09at org.apache.lucene.index.DefaultIndexingChain.process= Document(DefaultIndexingChain.java:392) [junit4] > =09at org.apache.lucene.index.DocumentsWriterPerThread.upd= ateDocument(DocumentsWriterPerThread.java:240) [junit4] > =09at org.apache.lucene.index.DocumentsWriter.updateDocume= nt(DocumentsWriter.java:497) [junit4] > =09at org.apache.lucene.index.IndexWriter.updateDocument(I= ndexWriter.java:1729) [junit4] > =09at org.apache.lucene.index.IndexWriter.addDocument(Inde= xWriter.java:1464) [junit4] > =09at org.apache.lucene.index.RandomIndexWriter.addDocumen= t(RandomIndexWriter.java:171) [junit4] > =09at org.apache.lucene.analysis.BaseTokenStreamTestCase.c= heckRandomData(BaseTokenStreamTestCase.java:672) [junit4] > =09at org.apache.lucene.analysis.BaseTokenStreamTestCase.c= heckRandomData(BaseTokenStreamTestCase.java:562) [junit4] > =09at org.apache.lucene.analysis.core.TestRandomChains.tes= tRandomChains(TestRandomChains.java:856) [junit4] > =09at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenk= ins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/anal= ysis/common/test/J1/temp/lucene.analysis.core.TestRandomChains_540F19286458= 13E-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene70): {dummy= =3DPostingsFormat(name=3DDirect)}, docValues:{}, maxPointsInLeafNode=3D1208= , maxMBSortInHeap=3D6.933392510734363, sim=3DRandomSimilarity(queryNorm=3Dt= rue): {dummy=3DDFR I(ne)L3(800.0)}, locale=3Des-BO, timezone=3DAsia/Karachi [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8= .0_144 (64-bit)/cpus=3D4,threads=3D1,free=3D89327176,total=3D269484032 [junit4] 2> NOTE: All tests run in this JVM: [TestGermanMinimalStemFil= terFactory, TestPortugueseMinimalStemFilter, TestUAX29URLEmailAnalyzer, Tes= tRomanianAnalyzer, TestLithuanianStemming, TestIrishAnalyzer, TestFingerpri= ntFilter, TestASCIIFoldingFilter, TestOptionalCondition, TestSynonymMapFilt= er, TestGermanNormalizationFilter, TestTypeTokenFilter, TestScandinavianFol= dingFilter, TestCzechAnalyzer, TestKeywordRepeatFilter, TestRemoveDuplicate= sTokenFilter, TestDanishAnalyzer, TestEmptyTokenStream, TestZeroAffix, Test= TurkishLowerCaseFilter, CommonGramsFilterTest, TestDictionaryCompoundWordTo= kenFilterFactory, TestDictionary, TestPatternCaptureGroupTokenFilter, TestL= engthFilter, TestSnowball, TestGermanLightStemFilterFactory, TestStemmerOve= rrideFilterFactory, TestBulgarianAnalyzer, TestApostropheFilterFactory, Tes= tCommonGramsQueryFilterFactory, TestGermanLightStemFilter, TestItalianLight= StemFilterFactory, TestEnglishMinimalStemFilterFactory, TestDecimalDigitFil= ter, TestAlternateCasing, TestPatternTokenizerFactory, TestPersianCharFilte= r, TestSwedishLightStemFilter, TestLimitTokenCountAnalyzer, TestSoraniNorma= lizationFilterFactory, TestWikipediaTokenizerFactory, TestTypeTokenFilterFa= ctory, TestPatternReplaceFilterFactory, TestCompoundWordTokenFilter, TestHy= phenatedWordsFilter, TestKeepCase, TestFlagLong, TestRandomChains] [junit4] Completed [153/285 (1!)] on J1 in 118.91s, 2 tests, 1 error <<<= FAILURES! [...truncated 9741 lines...] [junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Luce= ne-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.cl= oud.autoscaling.TriggerIntegrationTest_F982B7BEA7225924-001/init-core-data-= 001 [junit4] 2> 294565 WARN (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpe= ns=3D2 numCloses=3D2 [junit4] 2> 294565 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POIN= TS_SYSPROP=3Dfalse) w/NUMERIC_DOCVALUES_SYSPROP=3Dtrue [junit4] 2> 294566 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clie= ntAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=3D, value=3DNa= N, ssl=3DNaN, clientAuth=3DNaN) [junit4] 2> 294567 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: te= st.solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urand= om [junit4] 2> 294567 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 = servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x= /checkout/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerI= ntegrationTest_F982B7BEA7225924-001/tempDir-001 [junit4] 2> 294568 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 294568 INFO (Thread-850) [ ] o.a.s.c.ZkTestServer clie= nt port:0.0.0.0/0.0.0.0:0 [junit4] 2> 294568 INFO (Thread-850) [ ] o.a.s.c.ZkTestServer Star= ting server [junit4] 2> 294575 ERROR (Thread-850) [ ] o.a.z.s.ZooKeeperServer Z= KShutdownHandler is not registered, so ZooKeeper server won't take any acti= on on ERROR or SHUTDOWN server state changes [junit4] 2> 294668 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:55487 [junit4] 2> 294672 INFO (zkConnectionManagerCallback-887-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 294678 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, g= it hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 294688 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, g= it hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 294688 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 294688 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= session No SessionScavenger set, using defaults [junit4] 2> 294688 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= session Scavenging every 660000ms [junit4] 2> 294688 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 294688 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= session No SessionScavenger set, using defaults [junit4] 2> 294688 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@1c4deccd{/solr,null,= AVAILABLE} [junit4] 2> 294688 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= session Scavenging every 660000ms [junit4] 2> 294689 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@5f789514{/solr,null,= AVAILABLE} [junit4] 2> 294694 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@10208b4e{SSL,[ssl, http/1.1]}{127= .0.0.1:50137} [junit4] 2> 294694 INFO (jetty-launcher-884-thread-2) [ ] o.e.j.s.= Server Started @297153ms [junit4] 2> 294695 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D5013= 7} [junit4] 2> 294695 ERROR (jetty-launcher-884-thread-2) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 294695 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 294695 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 294695 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 294695 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-22T03:49:32.227Z [junit4] 2> 294695 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@202e7f96{SSL,[ssl, http/1.1]}{127= .0.0.1:48012} [junit4] 2> 294696 INFO (jetty-launcher-884-thread-1) [ ] o.e.j.s.= Server Started @297155ms [junit4] 2> 294696 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D4801= 2} [junit4] 2> 294696 ERROR (jetty-launcher-884-thread-1) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 294696 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.3.0 [junit4] 2> 294696 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 294696 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 294696 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-22T03:49:32.228Z [junit4] 2> 294712 INFO (zkConnectionManagerCallback-889-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 294713 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 294720 INFO (zkConnectionManagerCallback-891-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 294721 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 294738 INFO (jetty-launcher-884-thread-2) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:55487/solr [junit4] 2> 294741 INFO (jetty-launcher-884-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:55487/solr [junit4] 2> 294751 INFO (zkConnectionManagerCallback-896-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 294751 INFO (zkConnectionManagerCallback-899-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 294764 INFO (zkConnectionManagerCallback-901-thread-1-pro= cessing-n:127.0.0.1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.Conn= ectionManager zkClient has connected [junit4] 2> 294768 INFO (zkConnectionManagerCallback-903-thread-1-pro= cessing-n:127.0.0.1:48012_solr) [n:127.0.0.1:48012_solr ] o.a.s.c.c.Conn= ectionManager zkClient has connected [junit4] 2> 294854 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 294854 INFO (jetty-launcher-884-thread-2) [n:127.0.0.1:50= 137_solr ] o.a.s.c.Overseer Overseer (id=3Dnull) closing [junit4] 2> 294854 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:48012_solr [junit4] 2> 294855 INFO (jetty-launcher-884-thread-2) [n:127.0.0.1:50= 137_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:50137_solr [junit4] 2> 294855 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.c.Overseer Overseer (id=3D73337071671443462-127.0.0.1:4= 8012_solr-n_0000000000) starting [junit4] 2> 294868 INFO (zkCallback-902-thread-1-processing-n:127.0.0= .1:48012_solr) [n:127.0.0.1:48012_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 294872 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 294901 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:48012_solr [junit4] 2> 294902 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 294903 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 294903 INFO (zkCallback-902-thread-1-processing-n:127.0.0= .1:48012_solr) [n:127.0.0.1:48012_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 294904 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version= 1 [junit4] 2> 294904 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:48012_solr, 127.0.0.1:5= 0137_solr] [junit4] 2> 294905 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1 [junit4] 2> 294905 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1 [junit4] 2> 294912 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 294912 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1 [junit4] 2> 294916 DEBUG (ScheduledTrigger-1246-thread-1) [n:127.0.0.1= :48012_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 294986 INFO (jetty-launcher-884-thread-2) [n:127.0.0.1:50= 137_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50137.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@51d6ce35 [junit4] 2> 294989 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48012.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@51d6ce35 [junit4] 2> 294996 INFO (jetty-launcher-884-thread-2) [n:127.0.0.1:50= 137_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50137.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@51d6ce35 [junit4] 2> 294996 INFO (jetty-launcher-884-thread-2) [n:127.0.0.1:50= 137_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50137.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@51d6ce35 [junit4] 2> 294997 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48012.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@51d6ce35 [junit4] 2> 294997 INFO (jetty-launcher-884-thread-2) [n:127.0.0.1:50= 137_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/check= out/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegra= tionTest_F982B7BEA7225924-001/tempDir-001/node2/. [junit4] 2> 294999 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_48012.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@51d6ce35 [junit4] 2> 295000 INFO (jetty-launcher-884-thread-1) [n:127.0.0.1:48= 012_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/check= out/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegra= tionTest_F982B7BEA7225924-001/tempDir-001/node1/. [junit4] 2> 295047 INFO (zkConnectionManagerCallback-909-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 295051 INFO (zkConnectionManagerCallback-913-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 295052 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Zoo= Keeper... (0) -> (2) [junit4] 2> 295052 INFO (SUITE-TriggerIntegrationTest-seed#[F982B7BEA= 7225924]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at= 127.0.0.1:55487/solr ready [junit4] 2> 295069 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testN= odeAddedTrigger [junit4] 2> 295102 INFO (qtp326589519-3548) [n:127.0.0.1:48012_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus = with params action=3DOVERSEERSTATUS&wt=3Djavabin&version=3D2 and sendToOCPQ= ueue=3Dtrue [junit4] 2> 295109 INFO (qtp326589519-3548) [n:127.0.0.1:48012_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{action=3DOVERSEERSTATUS&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D7 [junit4] 2> 295113 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.ScheduledTriggers -- removin= g trigger: .auto_add_replicas [junit4] 2> 295114 DEBUG (zkCallback-902-thread-1-processing-n:127.0.0= .1:48012_solr) [n:127.0.0.1:48012_solr ] o.a.s.c.a.OverseerTriggerThread= Refreshing /autoscaling.json with znode version 2 [junit4] 2> 295114 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2 [junit4] 2> 295115 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 295115 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 295115 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2 [junit4] 2> 295115 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.TriggerIntegrationTest /auto= scaling.json reset, new znode version 2 [junit4] 2> 295116 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.c.ZkStateReader Updated live n= odes from ZooKeeper... (0) -> (2) [junit4] 2> 295117 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.AbstractConnector Stopped Serv= erConnector@202e7f96{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 295117 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.CoreContainer Shutting down Co= reContainer instance=3D1052138497 [junit4] 2> 295117 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closing metr= ic reporters for registry=3Dsolr.node, tag=3Dnull [junit4] 2> 295118 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.r.SolrJmxReporter Closing repo= rter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c0cb1: rootName = =3D solr_48012, domain =3D solr.node, service url =3D null, agent id =3D nu= ll] for registry solr.node / com.codahale.metrics.MetricRegistry@20e3969b [junit4] 2> 295123 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closing metr= ic reporters for registry=3Dsolr.jvm, tag=3Dnull [junit4] 2> 295123 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.r.SolrJmxReporter Closing repo= rter [org.apache.solr.metrics.reporters.SolrJmxReporter@20590504: rootName = =3D solr_48012, domain =3D solr.jvm, service url =3D null, agent id =3D nul= l] for registry solr.jvm / com.codahale.metrics.MetricRegistry@67ee75a1 [junit4] 2> 295127 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closing metr= ic reporters for registry=3Dsolr.jetty, tag=3Dnull [junit4] 2> 295127 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.r.SolrJmxReporter Closing repo= rter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b95a740: rootName = =3D solr_48012, domain =3D solr.jetty, service url =3D null, agent id =3D n= ull] for registry solr.jetty / com.codahale.metrics.MetricRegistry@c7cf966 [junit4] 2> 295128 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.ZkController Remove node as li= ve in ZooKeeper:/live_nodes/127.0.0.1:48012_solr [junit4] 2> 295129 INFO (zkCallback-902-thread-1-processing-n:127.0.0= .1:48012_solr) [n:127.0.0.1:48012_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 295130 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 295130 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closing metr= ic reporters for registry=3Dsolr.cluster, tag=3Dnull [junit4] 2> 295130 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.TransientSolrCoreCacheDefault = Allocating transient cache for 2147483647 transient cores [junit4] 2> 295140 INFO (zkCallback-912-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 295140 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.Overseer Overseer (id=3D733370= 71671443462-127.0.0.1:48012_solr-n_0000000000) closing [junit4] 2> 295141 INFO (OverseerStateUpdate-73337071671443462-127.0.= 0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.s.c.Overseer = Overseer Loop exiting : 127.0.0.1:48012_solr [junit4] 2> 295141 WARN (OverseerAutoScalingTriggerThread-73337071671= 443462-127.0.0.1:48012_solr-n_0000000000) [n:127.0.0.1:48012_solr ] o.a.= s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are close= d, exiting. [junit4] 2> 295147 DEBUG (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.OverseerTriggerThread Overse= erTriggerThread has been closed explicitly [junit4] 2> 295148 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.OverseerElectionContext= I am going to be the leader 127.0.0.1:50137_solr [junit4] 2> 295148 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j= .s.ServletContextHandler@5f789514{/solr,null,UNAVAILABLE} [junit4] 2> 295148 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session Stopped scavenging [junit4] 2> 295149 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.Overseer Overseer (id= =3D73337071671443461-127.0.0.1:50137_solr-n_0000000001) starting [junit4] 2> 295159 INFO (zkCallback-912-thread-2) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 295159 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 295160 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version= 3 [junit4] 2> 295160 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:50137_solr] [junit4] 2> 295160 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion -1 [junit4] 2> 295160 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3 [junit4] 2> 295162 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 295162 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3 [junit4] 2> 295163 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 1 [junit4] 2> 296163 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 1 [junit4] 2> 297164 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 1 [junit4] 2> 298164 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 1 [junit4] 2> 299164 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 1 [junit4] 2> 300151 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, = build timestamp: 2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329= d540ce37a2a2615f0a8 [junit4] 2> 300151 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session DefaultSessionIdManage= r workerName=3Dnode0 [junit4] 2> 300151 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session No SessionScavenger se= t, using defaults [junit4] 2> 300151 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session Scavenging every 60000= 0ms [junit4] 2> 300152 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.h.ContextHandler Started o.e.j= .s.ServletContextHandler@6aa46f6{/solr,null,AVAILABLE} [junit4] 2> 300152 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.AbstractConnector Started Serv= erConnector@6b8410bb{SSL,[ssl, http/1.1]}{127.0.0.1:58479} [junit4] 2> 300152 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.Server Started @302612ms [junit4] 2> 300152 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty prop= erties: {hostContext=3D/solr, hostPort=3D58479} [junit4] 2> 300153 ERROR (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.u.StartupLoggingUtils Missing Ja= va Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 300153 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter ___ _= Welcome to Apache Solr? version 7.3.0 [junit4] 2> 300153 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| = |_ _ Starting in cloud mode on port null [junit4] 2> 300153 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ = | '_| Install dir: null [junit4] 2> 300153 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_= |_| Start time: 2018-02-22T03:49:37.685Z [junit4] 2> 300162 INFO (zkConnectionManagerCallback-916-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 300162 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter solr.xml fo= und in ZooKeeper. Loading... [junit4] 2> 300165 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 1 [junit4] 2> 300169 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.ZkContainer Zookeeper client= =3D127.0.0.1:55487/solr [junit4] 2> 300171 INFO (zkConnectionManagerCallback-920-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 300196 INFO (zkConnectionManagerCallback-922-thread-1-pro= cessing-n:127.0.0.1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.c.Conn= ectionManager zkClient has connected [junit4] 2> 300202 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.c.c.ZkStat= eReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 300204 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.c.Overseer= Overseer (id=3Dnull) closing [junit4] 2> 300205 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.c.ZkContro= ller Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58479_solr [junit4] 2> 300212 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 300212 INFO (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 300212 INFO (zkCallback-912-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 300212 INFO (zkCallback-912-thread-2) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 300288 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.m.r.SolrJm= xReporter JMX monitoring for 'solr_58479.solr.node' (registry 'solr.node') = enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@51d6ce35 [junit4] 2> 300296 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.m.r.SolrJm= xReporter JMX monitoring for 'solr_58479.solr.jvm' (registry 'solr.jvm') en= abled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@51d6ce35 [junit4] 2> 300296 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.m.r.SolrJm= xReporter JMX monitoring for 'solr_58479.solr.jetty' (registry 'solr.jetty'= ) enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@51d6ce35 [junit4] 2> 300297 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:58479_solr ] o.a.s.c.CoreProp= ertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave= /workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/= J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F982B7BEA7225924-001/= tempDir-001/node3/. [junit4] 2> 300386 INFO (qtp1077474312-3543) [n:127.0.0.1:50137_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D8 [junit4] 2> 300416 INFO (qtp1332162489-3615) [n:127.0.0.1:58479_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D0 [junit4] 2> 300418 DEBUG (qtp1077474312-3540) [n:127.0.0.1:50137_solr = ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 300419 INFO (qtp1077474312-3540) [n:127.0.0.1:50137_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/autoscaling p= arams=3D{wt=3Djavabin&version=3D2} status=3D0 QTime=3D59 [junit4] 2> 300419 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.OverseerTriggerThread= Refreshing /autoscaling.json with znode version 4 [junit4] 2> 300424 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.NodeLostTrigger Initi= al livenodes: [127.0.0.1:50137_solr, 127.0.0.1:58479_solr] [junit4] 2> 300426 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.NodeAddedTrigger Init= ial livenodes: [127.0.0.1:50137_solr, 127.0.0.1:58479_solr] [junit4] 2> 300426 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.NodeAddedTrigger Node= AddedTrigger node_added_trigger instantiated with properties: {event=3Dnode= Added, waitFor=3D2, actions=3D[{name=3Dtest, class=3Dorg.apache.solr.cloud.= autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=3Dtrue} [junit4] 2> 300426 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4 [junit4] 2> 300428 INFO (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 300429 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, = build timestamp: 2017-11-21T18:27:37-03:00, git hash: 82b8fb23f757335bb3329= d540ce37a2a2615f0a8 [junit4] 2> 300430 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4 [junit4] 2> 300431 DEBUG (ScheduledTrigger-1255-thread-3) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_a= dded_trigger [junit4] 2> 300431 DEBUG (ScheduledTrigger-1255-thread-3) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 [junit4] 2> 300432 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session DefaultSessionIdManage= r workerName=3Dnode0 [junit4] 2> 300432 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session No SessionScavenger se= t, using defaults [junit4] 2> 300432 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session Scavenging every 66000= 0ms [junit4] 2> 300433 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.h.ContextHandler Started o.e.j= .s.ServletContextHandler@2359b2a6{/solr,null,AVAILABLE} [junit4] 2> 300433 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.AbstractConnector Started Serv= erConnector@2aa70b20{SSL,[ssl, http/1.1]}{127.0.0.1:33393} [junit4] 2> 300433 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.e.j.s.Server Started @302893ms [junit4] 2> 300433 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty prop= erties: {hostContext=3D/solr, hostPort=3D33393} [junit4] 2> 300434 ERROR (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.u.StartupLoggingUtils Missing Ja= va Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 300434 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter ___ _= Welcome to Apache Solr? version 7.3.0 [junit4] 2> 300434 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| = |_ _ Starting in cloud mode on port null [junit4] 2> 300434 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ = | '_| Install dir: null [junit4] 2> 300434 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_= |_| Start time: 2018-02-22T03:49:37.966Z [junit4] 2> 300440 INFO (zkConnectionManagerCallback-926-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 300441 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.s.SolrDispatchFilter solr.xml fo= und in ZooKeeper. Loading... [junit4] 2> 300465 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.c.ZkContainer Zookeeper client= =3D127.0.0.1:55487/solr [junit4] 2> 300468 INFO (zkConnectionManagerCallback-930-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 300473 INFO (zkConnectionManagerCallback-932-thread-1-pro= cessing-n:127.0.0.1:33393_solr) [n:127.0.0.1:33393_solr ] o.a.s.c.c.Conn= ectionManager zkClient has connected [junit4] 2> 300478 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.c.c.ZkStat= eReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 300480 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.c.Overseer= Overseer (id=3Dnull) closing [junit4] 2> 300482 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.c.ZkContro= ller Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33393_solr [junit4] 2> 300483 INFO (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 300483 INFO (zkCallback-912-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 300483 INFO (zkCallback-912-thread-2) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 300488 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 300496 INFO (zkCallback-931-thread-1-processing-n:127.0.0= .1:33393_solr) [n:127.0.0.1:33393_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 300558 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.m.r.SolrJm= xReporter JMX monitoring for 'solr_33393.solr.node' (registry 'solr.node') = enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@51d6ce35 [junit4] 2> 300567 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.m.r.SolrJm= xReporter JMX monitoring for 'solr_33393.solr.jvm' (registry 'solr.jvm') en= abled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@51d6ce35 [junit4] 2> 300567 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.m.r.SolrJm= xReporter JMX monitoring for 'solr_33393.solr.jetty' (registry 'solr.jetty'= ) enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@51d6ce35 [junit4] 2> 300569 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [n:127.0.0.1:33393_solr ] o.a.s.c.CoreProp= ertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave= /workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/= J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_F982B7BEA7225924-001/= tempDir-001/node4/. [junit4] 2> 301165 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 3 [junit4] 2> 301433 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_a= dded_trigger [junit4] 2> 301433 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 301433 DEBUG (ScheduledTrigger-1255-thread-1) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:33= 393_solr at time 19523286184971025 [junit4] 2> 302166 DEBUG (ScheduledTrigger-1255-thread-4) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 3 [junit4] 2> 302435 DEBUG (ScheduledTrigger-1255-thread-4) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_a= dded_trigger [junit4] 2> 302435 DEBUG (ScheduledTrigger-1255-thread-4) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 303166 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 3 [junit4] 2> 303435 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_a= dded_trigger [junit4] 2> 303435 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3 [junit4] 2> 303435 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_tri= gger firing registered processor for nodes: [127.0.0.1:33393_solr] added at= times [19523286184971025], now=3D19523288187573179 [junit4] 2> 303438 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - p= rocessing event: { [junit4] 2> "id":"455c5349e74b11Tbgvr7hdentdifvxkzuxq6x2l4", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":19523286184971025, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[19523286184971025], [junit4] 2> "nodeNames":["127.0.0.1:33393_solr"]}} [junit4] 2> 303444 DEBUG (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_a= dd_replicas, node_added_trigger] [junit4] 2> 303447 INFO (ScheduledTrigger-1255-thread-2) [n:127.0.0.1= :50137_solr ] o.a.s.c.a.SystemLogListener Collection .system does not ex= ist, disabling logging. [junit4] 2> 303453 DEBUG (AutoscalingActionExecutor-1256-thread-1-proc= essing-n:127.0.0.1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.Sched= uledTriggers -- processing actions for { [junit4] 2> "id":"455c5349e74b11Tbgvr7hdentdifvxkzuxq6x2l4", [junit4] 2> "source":"node_added_trigger", [junit4] 2> "eventTime":19523286184971025, [junit4] 2> "eventType":"NODEADDED", [junit4] 2> "properties":{ [junit4] 2> "eventTimes":[19523286184971025], [junit4] 2> "_enqueue_time_":19523288197241165, [junit4] 2> "nodeNames":["127.0.0.1:33393_solr"]}} [junit4] 2> 303474 DEBUG (AutoscalingActionExecutor-1256-thread-1-proc= essing-n:127.0.0.1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.Sched= uledTriggers Resuming trigger: .auto_add_replicas after 5000ms [junit4] 2> 303475 DEBUG (AutoscalingActionExecutor-1256-thread-1-proc= essing-n:127.0.0.1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.Sched= uledTriggers Resuming trigger: node_added_trigger after 5000ms [junit4] 2> 303475 DEBUG (AutoscalingActionExecutor-1256-thread-1-proc= essing-n:127.0.0.1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.Sched= uledTriggers -- processing took 22 ms for event id=3D455c5349e74b11Tbgvr7hd= entdifvxkzuxq6x2l4 [junit4] 2> 303495 INFO (qtp2134324161-3636) [n:127.0.0.1:33393_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D0 [junit4] 2> 303497 INFO (qtp1077474312-3540) [n:127.0.0.1:50137_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D0 [junit4] 2> 303506 INFO (qtp1332162489-3616) [n:127.0.0.1:58479_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D0 [junit4] 2> 303507 DEBUG (qtp2134324161-3639) [n:127.0.0.1:33393_solr = ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 303507 INFO (qtp2134324161-3639) [n:127.0.0.1:33393_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/autoscaling p= arams=3D{wt=3Djavabin&version=3D2} status=3D0 QTime=3D16 [junit4] 2> 303508 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.OverseerTriggerThread= Refreshing /autoscaling.json with znode version 5 [junit4] 2> 303508 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.NodeLostTrigger Initi= al livenodes: [127.0.0.1:33393_solr, 127.0.0.1:50137_solr, 127.0.0.1:58479_= solr] [junit4] 2> 303508 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.NodeAddedTrigger Init= ial livenodes: [127.0.0.1:33393_solr, 127.0.0.1:50137_solr, 127.0.0.1:58479= _solr] [junit4] 2> 303508 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.NodeAddedTrigger Node= AddedTrigger node_added_trigger instantiated with properties: {event=3Dnode= Added, waitFor=3D2, actions=3D[{name=3Dtest, class=3Dorg.apache.solr.cloud.= autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=3Dtrue} [junit4] 2> 303508 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5 [junit4] 2> 303509 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5 [junit4] 2> 305508 INFO (TEST-TriggerIntegrationTest.testNodeAddedTri= gger-seed#[F982B7BEA7225924]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNod= eAddedTrigger [junit4] 2> 305531 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.SolrTestCaseJ4 ###Starting= testEventFromRestoredState [junit4] 2> 305532 INFO (qtp2134324161-3641) [n:127.0.0.1:33393_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus= with params action=3DOVERSEERSTATUS&wt=3Djavabin&version=3D2 and sendToOCP= Queue=3Dtrue [junit4] 2> 305543 INFO (qtp2134324161-3641) [n:127.0.0.1:33393_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections p= arams=3D{action=3DOVERSEERSTATUS&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D10 [junit4] 2> 305548 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.ScheduledTriggers -- r= emoving trigger: .auto_add_replicas [junit4] 2> 305549 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.ScheduledTriggers -- r= emoving trigger: node_added_trigger [junit4] 2> 305549 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.TriggerIntegrationTest= /autoscaling.json reset, new znode version 6 [junit4] 2> 305550 DEBUG (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.a.OverseerTriggerThread= Refreshing /autoscaling.json with znode version 6 [junit4] 2> 305550 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6 [junit4] 2> 305550 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeLost markers [junit4] 2> 305550 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.c.ZkStateReader Updated = live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 305550 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 305551 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6 [junit4] 2> 305556 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.e.j.s.AbstractConnector Stoppe= d ServerConnector@10208b4e{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 305559 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.CoreContainer Shutting d= own CoreContainer instance=3D1669602204 [junit4] 2> 305559 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closin= g metric reporters for registry=3Dsolr.node, tag=3Dnull [junit4] 2> 305560 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.r.SolrJmxReporter Closin= g reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d0e1636: roo= tName =3D solr_50137, domain =3D solr.node, service url =3D null, agent id = =3D null] for registry solr.node / com.codahale.metrics.MetricRegistry@47b2= 56bd [junit4] 2> 305566 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closin= g metric reporters for registry=3Dsolr.jvm, tag=3Dnull [junit4] 2> 305566 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.r.SolrJmxReporter Closin= g reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21e73b99: roo= tName =3D solr_50137, domain =3D solr.jvm, service url =3D null, agent id = =3D null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@67ee7= 5a1 [junit4] 2> 305570 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closin= g metric reporters for registry=3Dsolr.jetty, tag=3Dnull [junit4] 2> 305570 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.r.SolrJmxReporter Closin= g reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5eb67dd7: roo= tName =3D solr_50137, domain =3D solr.jetty, service url =3D null, agent id= =3D null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@c7= cf966 [junit4] 2> 305571 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.ZkController Remove node= as live in ZooKeeper:/live_nodes/127.0.0.1:50137_solr [junit4] 2> 305572 INFO (zkCallback-931-thread-1-processing-n:127.0.0= .1:33393_solr) [n:127.0.0.1:33393_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 305572 INFO (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 305572 INFO (zkCallback-912-thread-2) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 305573 INFO (zkCallback-912-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 305573 INFO (zkCallback-900-thread-1-processing-n:127.0.0= .1:50137_solr) [n:127.0.0.1:50137_solr ] o.a.s.c.c.ZkStateReader Updated= live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 305573 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.m.SolrMetricManager Closin= g metric reporters for registry=3Dsolr.cluster, tag=3Dnull [junit4] 2> 305573 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.TransientSolrCoreCacheDe= fault Allocating transient cache for 2147483647 transient cores [junit4] 2> 305573 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.Overseer Overseer (id=3D= 73337071671443461-127.0.0.1:50137_solr-n_0000000001) closing [junit4] 2> 305573 INFO (OverseerStateUpdate-73337071671443461-127.0.= 0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.s.c.Overseer = Overseer Loop exiting : 127.0.0.1:50137_solr [junit4] 2> 305574 WARN (OverseerAutoScalingTriggerThread-73337071671= 443461-127.0.0.1:50137_solr-n_0000000001) [n:127.0.0.1:50137_solr ] o.a.= s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are close= d, exiting. [junit4] 2> 305574 DEBUG (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.a.s.c.a.OverseerTriggerThread = OverseerTriggerThread has been closed explicitly [junit4] 2> 305582 INFO (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.OverseerElectionContext= I am going to be the leader 127.0.0.1:58479_solr [junit4] 2> 305583 INFO (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.Overseer Overseer (id= =3D73337071671443467-127.0.0.1:58479_solr-n_0000000002) starting [junit4] 2> 305585 INFO (zkCallback-912-thread-3) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 305593 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.e.j.s.h.ContextHandler Stopped= o.e.j.s.ServletContextHandler@1c4deccd{/solr,null,UNAVAILABLE} [junit4] 2> 305593 INFO (TEST-TriggerIntegrationTest.testEventFromRes= toredState-seed#[F982B7BEA7225924]) [ ] o.e.j.s.session Stopped scavengi= ng [junit4] 2> 305594 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger [junit4] 2> 305594 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version= 7 [junit4] 2> 305595 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:33393_solr, 127.0.0.1:5= 8479_solr] [junit4] 2> 305595 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion -1 [junit4] 2> 305595 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7 [junit4] 2> 305600 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread -- clean old nodeAdded markers [junit4] 2> 305600 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7 [junit4] 2> 305600 DEBUG (ScheduledTrigger-1272-thread-1) [n:127.0.0.1= :58479_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 306601 DEBUG (ScheduledTrigger-1272-thread-1) [n:127.0.0.1= :58479_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 307602 DEBUG (ScheduledTrigger-1272-thread-2) [n:127.0.0.1= :58479_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 308602 DEBUG (ScheduledTrigger-1272-thread-1) [n:127.0.0.1= :58479_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 309602 DEBUG (ScheduledTrigger-1272-thread-1) [n:127.0.0.1= :58479_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 310602 INFO (qtp2134324161-3643) [n:127.0.0.1:33393_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D1 [junit4] 2> 310603 DEBUG (ScheduledTrigger-1272-thread-1) [n:127.0.0.1= :58479_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_a= dd_replicas with currently live nodes: 2 [junit4] 2> 310607 INFO (qtp1332162489-3615) [n:127.0.0.1:58479_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/metrics param= s=3D{prefix=3DCONTAINER.fs.usableSpace,CORE.coreName&wt=3Djavabin&version= =3D2&group=3Dsolr.node,solr.core} status=3D0 QTime=3D0 [junit4] 2> 310608 DEBUG (qtp2134324161-3639) [n:127.0.0.1:33393_solr = ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration [junit4] 2> 310610 INFO (qtp2134324161-3639) [n:127.0.0.1:33393_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/autoscaling p= arams=3D{wt=3Djavabin&version=3D2} status=3D0 QTime=3D11 [junit4] 2> 310610 DEBUG (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.a.OverseerTriggerThread= Refreshing /autoscaling.json with znode version 8 [junit4] 2> 310611 DEBUG (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.a.NodeAddedTrigger Init= ial livenodes: [127.0.0.1:33393_solr, 127.0.0.1:58479_solr] [junit4] 2> 310611 DEBUG (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.a.NodeAddedTrigger Node= AddedTrigger node_added_triggerEFRS instantiated with properties: {event=3D= nodeAdded, waitFor=3D10, actions=3D[{name=3Dtest, class=3Dorg.apache.solr.c= loud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=3Dtrue= } [junit4] 2> 310611 DEBUG (zkCallback-921-thread-1-processing-n:127.0.0= .1:58479_solr) [n:127.0.0.1:58479_solr ] o.a.s.c.a.NodeLostTrigger Initi= al livenodes: [127.0.0.1:33393_solr, 127.0.0.1:58479_solr] [junit4] 2> 310611 DEBUG (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8 [junit4] 2> 310614 INFO (OverseerAutoScalingTriggerThread-73337071671= 443467-127.0.0.1:58479_solr-n_0000000002) [n:127.0.0.1:58479_solr ] o.a.= s.c.a.TriggerIntegrationTest TestTriggerAction init [junit4] 2> 310614 [...truncated too long message...] Tests-7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsBa= sicDistributedZkTest_F982B7BEA7225924-001/tempDir-001/hdfsBaseDir/data/data= 1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests= -7.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsBasicDi= stributedZkTest_F982B7BEA7225924-001/tempDir-001/hdfsBaseDir/data/data2/]] = heartbeating to localhost/127.0.0.1:41537) [ ] o.a.h.h.s.d.IncrementalB= lockReportManager IncrementalBlockReportManager interrupted [junit4] 2> 4543399 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-= slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/= test/J1/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_F982B7BEA7225924-00= 1/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave= /workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/= J1/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_F982B7BEA7225924-001/tem= pDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:415= 37) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool B= P-104772299-10.41.0.5-1519275540093 (Datanode Uuid b3ddc592-a7e2-4cbf-b240-= 42c510692582) service to localhost/127.0.0.1:41537 [junit4] 2> 4543408 INFO (SUITE-HdfsBasicDistributedZkTest-seed#[F982= B7BEA7225924]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConne= ctorWithSafeStartup@localhost:0 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenk= ins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-c= ore/test/J1/temp/solr.cloud.hdfs.HdfsBasicDistributedZkTest_F982B7BEA722592= 4-001 [junit4] 2> Feb 22, 2018 5:00:20 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 21 leaked thr= ead(s). [junit4] 2> Feb 22, 2018 5:00:41 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 3 threads leaked from SUITE scope at org.apache.so= lr.cloud.hdfs.HdfsBasicDistributedZkTest:=20 [junit4] 2> 1) Thread[id=3D48716, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] 2> at java.lang.Thread.sleep(Native Method) [junit4] 2> at org.apache.http.impl.client.IdleConnectionEvict= or$1.run(IdleConnectionEvictor.java:66) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 2) Thread[id=3D48724, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] 2> at java.lang.Thread.sleep(Native Method) [junit4] 2> at org.apache.http.impl.client.IdleConnectionEvict= or$1.run(IdleConnectionEvictor.java:66) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 3) Thread[id=3D48734, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] 2> at java.lang.Thread.sleep(Native Method) [junit4] 2> at org.apache.http.impl.client.IdleConnectionEvict= or$1.run(IdleConnectionEvictor.java:66) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> Feb 22, 2018 5:00:41 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=3D48716, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] 2> 2) Thread[id=3D48724, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] 2> 3) Thread[id=3D48734, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] 2> Feb 22, 2018 5:00:41 AM com.carrotsearch.randomizedtesting= .ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=3DLucene70, sim=3DRandomSimil= arity(queryNorm=3Dtrue): {}, locale=3Den-GB, timezone=3DAmerica/Indiana/Mar= engo [junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8= .0_144 (64-bit)/cpus=3D4,threads=3D2,free=3D226443792,total=3D516423680 [junit4] 2> NOTE: All tests run in this JVM: [SOLR749Test, BasicZkTest= , ShardRoutingCustomTest, TestSystemIdResolver, TestLRUCache, DistributedFa= cetPivotLargeTest, TestNumericRangeQuery64, QueryParsingTest, SystemInfoHan= dlerTest, TestRestManager, BadCopyFieldTest, TestXIncludeConfig, AsyncCallR= equestStatusResponseTest, PathHierarchyTokenizerFactoryTest, HdfsDirectoryF= actoryTest, ComputePlanActionTest, HdfsCollectionsAPIDistributedZkTest, Sas= lZkACLProviderTest, TestDynamicFieldCollectionResource, ZkStateReaderTest, = TestJavabinTupleStreamParser, InfixSuggestersTest, TestSystemCollAutoCreate= , SuggesterFSTTest, RestartWhileUpdatingTest, CurrencyFieldTypeTest, TestSc= hemaManager, TestFieldCache, TestTrie, FastVectorHighlighterTest, SolrCoreT= est, SpatialHeatmapFacetsTest, ZkControllerTest, SampleTest, AutoAddReplica= sIntegrationTest, DistributedFacetPivotSmallTest, TestPivotHelperCode, Test= SearchPerf, SolrIndexMetricsTest, TestStressVersions, TestRTGBase, TestZkCh= root, TestInfoStreamLogging, DirectUpdateHandlerOptimizeTest, SolrJmxReport= erTest, TestJettySolrRunner, TestCoreBackup, TestDistribIDF, TestCloudDelet= eByQuery, PrimitiveFieldTypeTest, BlockCacheTest, TestSurroundQueryParser, = HdfsRecoveryZkTest, CSVRequestHandlerTest, JvmMetricsTest, TestPolicyCloud,= ZkFailoverTest, MetricUtilsTest, HdfsThreadLeakTest, TestReloadAndDeleteDo= cs, TestMacroExpander, BitVectorTest, CdcrUpdateLogTest, HLLSerializationTe= st, TestSchemaVersionResource, HdfsWriteToMultipleCollectionsTest, SyncSlic= eTest, TestImpersonationWithHadoopAuth, TestSubQueryTransformerDistrib, Sig= natureUpdateProcessorFactoryTest, HighlighterTest, TestDistributedStatsComp= onentCardinality, DOMUtilTest, TestConfigSets, ResourceLoaderTest, PreAnaly= zedUpdateProcessorTest, DocValuesNotIndexedTest, TestRecovery, TestRuleBase= dAuthorizationPlugin, TestUninvertingReader, TestFieldSortValues, BlockJoin= FacetSimpleTest, DistribJoinFromCollectionTest, AutoscalingHistoryHandlerTe= st, SolrMetricManagerTest, TestCollationFieldDocValues, UpdateLogTest, Test= SolrFieldCacheBean, TestRandomRequestDistribution, JsonLoaderTest, Document= BuilderTest, SolrCloudExampleTest, TestPartialUpdateDeduplication, FileUtil= sTest, BJQParserTest, TestLRUStatsCache, LegacyCloudClusterPropTest, TestCl= assicSimilarityFactory, DistributedQueryComponentOptimizationTest, TestMini= SolrCloudClusterSSL, TestEmbeddedSolrServerAdminHandler, QueryEqualityTest,= TlogReplayBufferedWhileIndexingTest, TestStressInPlaceUpdates, LoggingHand= lerTest, TestCollectionAPI, TestLeaderElectionZkExpiry, TestSolrCLIRunExamp= le, TestDownShardTolerantSearch, SchemaVersionSpecificBehaviorTest, AtomicU= pdateProcessorFactoryTest, DistributedIntervalFacetingTest, TestComplexPhra= seLeadingWildcard, TestSolrCoreSnapshots, TestMaxScoreQueryParser, Overridd= enZkACLAndCredentialsProvidersTest, TestLegacyTerms, TestComputePlanAction,= AddReplicaTest, TestManagedSchemaAPI, SmileWriterTest, TestHashQParserPlug= in, StatsReloadRaceTest, AlternateDirectoryTest, TestFieldResource, TestMan= agedStopFilterFactory, DeleteStatusTest, CdcrBootstrapTest, BasicDistribute= dZkTest, MigrateRouteKeyTest, MoveReplicaHDFSTest, SolrXmlInZkTest, TestAut= henticationFramework, TestCloudPivotFacet, TestClusterProperties, TestConfi= gSetsAPI, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestLockT= ree, TestOnReconnectListenerSupport, TestPullReplicaErrorHandling, TestSSLR= andomization, TestSegmentSorting, TestSolrCloudWithKerberosAlt, TestToleran= tUpdateProcessorCloud, TestUtilizeNode, AssignTest, CollectionReloadTest, C= ollectionsAPIDistributedZkTest, TestHdfsCloudBackupRestore, AutoAddReplicas= PlanActionTest, NodeLostTriggerTest, TestTriggerIntegration, CdcrReplicatio= nHandlerTest, HdfsBasicDistributedZkTest] [junit4] 2> NOTE: download the large Jenkins line-docs file by running= 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DHdfsBasicDist= ributedZkTest -Dtests.seed=3DF982B7BEA7225924 -Dtests.multiplier=3D2 -Dtest= s.nightly=3Dtrue -Dtests.slow=3Dtrue -Dtests.linedocsfile=3D/home/jenkins/j= enkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/test-data/enwiki.random= .lines.txt -Dtests.locale=3Den-GB -Dtests.timezone=3DAmerica/Indiana/Mareng= o -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUS-ASCII [junit4] ERROR 0.00s J1 | HdfsBasicDistributedZkTest (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLea= kError: 3 threads leaked from SUITE scope at org.apache.solr.cloud.hdfs.Hdf= sBasicDistributedZkTest:=20 [junit4] > 1) Thread[id=3D48716, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.http.impl.client.IdleConnectionEvict= or$1.run(IdleConnectionEvictor.java:66) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 2) Thread[id=3D48724, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.http.impl.client.IdleConnectionEvict= or$1.run(IdleConnectionEvictor.java:66) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > 3) Thread[id=3D48734, name=3DConnection evictor, state= =3DTIMED_WAITING, group=3DTGRP-HdfsBasicDistributedZkTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.http.impl.client.IdleConnectionEvict= or$1.run(IdleConnectionEvictor.java:66) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > =09at __randomizedtesting.SeedInfo.seed([F982B7BEA7225924]= :0) [junit4] Completed [615/777 (4!)] on J1 in 101.08s, 1 test, 2 errors <<<= FAILURES! [...truncated 49010 lines...] ------=_Part_95_683276157.1519277551011 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_95_683276157.1519277551011--