From dev-return-355223-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Tue May 7 21:26:27 2019 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 [207.244.88.153]) by mx-eu-01.ponee.io (Postfix) with SMTP id 2414F18064C for ; Tue, 7 May 2019 23:26:25 +0200 (CEST) Received: (qmail 67013 invoked by uid 500); 7 May 2019 21:25:48 -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 66998 invoked by uid 99); 7 May 2019 21:25:47 -0000 Received: from Unknown (HELO mailrelay2-lw-us.apache.org) (10.10.3.159) by apache.org (qpsmtpd/0.29) with ESMTP; Tue, 07 May 2019 21:25:47 +0000 Received: from jenkins02.apache.org (jenkins02.apache.org [195.201.213.130]) by mailrelay2-lw-us.apache.org (ASF Mail Server at mailrelay2-lw-us.apache.org) with ESMTP id 1EC9D4016 for ; Tue, 7 May 2019 21:25:46 +0000 (UTC) Received: from jenkins02.apache.org (localhost.localdomain [127.0.0.1]) by jenkins02.apache.org (ASF Mail Server at jenkins02.apache.org) with ESMTP id DC87933E003D for ; Tue, 7 May 2019 21:25:45 +0000 (UTC) Date: Tue, 7 May 2019 21:22:45 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <1212756035.8831.1557264345905.JavaMail.jenkins@jenkins02> Subject: [JENKINS] Lucene-Solr-Tests-master - Build # 3333 - Unstable MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_8830_1757466350.1557264345794" X-Jenkins-Job: Lucene-Solr-Tests-master X-Jenkins-Result: UNSTABLE ------=_Part_8830_1757466350.1557264345794 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/3333/ 4 tests failed. FAILED: org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProvide= rAPI Error Message: should be a routed alias Stack Trace: java.lang.AssertionError: should be a routed alias =09at __randomizedtesting.SeedInfo.seed([DD8438EB6C3D353B:C253A4C71F36CC70]= :0) =09at org.junit.Assert.fail(Assert.java:88) =09at org.junit.Assert.assertTrue(Assert.java:41) =09at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAP= I(AliasIntegrationTest.java:302) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:566) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1750) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:938) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:974) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:988) =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:947) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:832) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:883) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:894) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.base/java.lang.Thread.run(Thread.java:834) FAILED: org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollect= ionSwitchLegacyCloud Error Message: Error from server at http://127.0.0.1:41531/solr: Underlying core creation = failed while creating collection: legacyFalse Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error= from server at http://127.0.0.1:41531/solr: Underlying core creation faile= d while creating collection: legacyFalse =09at __randomizedtesting.SeedInfo.seed([DD8438EB6C3D353B:C83CA6EC832BE09]:= 0) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:649) =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.LBSolrClient.doRequest(LBSolrClient= .java:368) =09at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.j= ava:296) =09at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(Bas= eCloudSolrClient.java:1068) =09at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetr= yOnStaleState(BaseCloudSolrClient.java:837) =09at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseClo= udSolrClient.java:769) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207= ) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224= ) =09at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(Legacy= CloudClusterPropTest.java:95) =09at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollection= SwitchLegacyCloud(LegacyCloudClusterPropTest.java:79) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:566) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1750) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:938) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:974) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:988) =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:947) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:832) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:883) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:894) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.base/java.lang.Thread.run(Thread.java:834) FAILED: org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollec= tionUpdateAndQueryDistributed Error Message: Expected metric minimums for prefix SECURITY./authentication/pki.: {failMis= singCredentials=3D0, authenticated=3D12, passThrough=3D0, failWrongCredenti= als=3D0, requests=3D12, errors=3D0}, but got: {failMissingCredentials=3D0, = authenticated=3D4, passThrough=3D0, totalTime=3D6667221, failWrongCredentia= ls=3D0, requestTimes=3D427, requests=3D4, errors=3D0} Stack Trace: java.lang.AssertionError: Expected metric minimums for prefix SECURITY./aut= hentication/pki.: {failMissingCredentials=3D0, authenticated=3D12, passThro= ugh=3D0, failWrongCredentials=3D0, requests=3D12, errors=3D0}, but got: {fa= ilMissingCredentials=3D0, authenticated=3D4, passThrough=3D0, totalTime=3D6= 667221, failWrongCredentials=3D0, requestTimes=3D427, requests=3D4, errors= =3D0} =09at __randomizedtesting.SeedInfo.seed([DD8438EB6C3D353B:DBAC3A850E8AA388]= :0) =09at org.junit.Assert.fail(Assert.java:88) =09at org.junit.Assert.assertTrue(Assert.java:41) =09at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums= (SolrCloudAuthTestCase.java:129) =09at org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinim= ums(SolrCloudAuthTestCase.java:74) =09at org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectio= nUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:566) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1750) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:938) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:974) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:988) =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:947) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:832) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:883) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:894) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.base/java.lang.Thread.run(Thread.java:834) FAILED: org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.= testBasics Error Message: Expected metric minimums for prefix SECURITY./authentication.: {failMissing= Credentials=3D0, authenticated=3D8, passThrough=3D0, failWrongCredentials= =3D8, requests=3D16, errors=3D0}, but got: {failMissingCredentials=3D0, aut= henticated=3D8, passThrough=3D0, totalTime=3D169891837, failWrongCredential= s=3D6, requestTimes=3D2501, requests=3D14, errors=3D0} Stack Trace: java.lang.AssertionError: Expected metric minimums for prefix SECURITY./aut= hentication.: {failMissingCredentials=3D0, authenticated=3D8, passThrough= =3D0, failWrongCredentials=3D8, requests=3D16, errors=3D0}, but got: {failM= issingCredentials=3D0, authenticated=3D8, passThrough=3D0, totalTime=3D1698= 91837, failWrongCredentials=3D6, requestTimes=3D2501, requests=3D14, errors= =3D0} =09at __randomizedtesting.SeedInfo.seed([DD8438EB6C3D353B:E05C96C754D36B4B]= :0) =09at org.junit.Assert.fail(Assert.java:88) =09at org.junit.Assert.assertTrue(Assert.java:41) =09at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums= (SolrCloudAuthTestCase.java:129) =09at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums= (SolrCloudAuthTestCase.java:83) =09at org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.tes= tCollectionCreateSearchDelete(TestSolrCloudWithHadoopAuthPlugin.java:139) =09at org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.tes= tBasics(TestSolrCloudWithHadoopAuthPlugin.java:108) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Nativ= e Method) =09at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Native= MethodAccessorImpl.java:62) =09at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(De= legatingMethodAccessorImpl.java:43) =09at java.base/java.lang.reflect.Method.invoke(Method.java:566) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1750) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:938) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:974) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:988) =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:947) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:832) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:883) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:894) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.base/java.lang.Thread.run(Thread.java:834) Build Log: [...truncated 12660 lines...] [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lu= cene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIn= tegrationTest_DD8438EB6C3D353B-001/init-core-data-001 [junit4] 2> 313314 WARN (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens= =3D33 numCloses=3D33 [junit4] 2> 313315 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINT= S_SYSPROP=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dtrue [junit4] 2> 313316 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clien= tAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=3D"", ssl=3D0.= 0/0.0, value=3D0.0/0.0, clientAuth=3D0.0/0.0) [junit4] 2> 313316 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test= .solr.allowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urandom [junit4] 2> 313316 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 se= rvers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/sol= r/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D= 353B-001/tempDir-001 [junit4] 2> 313317 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 313386 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTe= stServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 313386 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTe= stServer Starting server [junit4] 2> 313495 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43032 [junit4] 2> 313495 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.= 0.1:43032 [junit4] 2> 313495 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43032 [junit4] 2> 313643 INFO (zkConnectionManagerCallback-322-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 313771 INFO (zkConnectionManagerCallback-324-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 313844 INFO (zkConnectionManagerCallback-326-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 313892 WARN (jetty-launcher-327-thread-2) [ ] o.e.j.s.= AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 313893 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Start Jetty (original configured port=3D0) [junit4] 2> 313893 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 313893 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c45500= 56e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS [junit4] 2> 313896 WARN (jetty-launcher-327-thread-1) [ ] o.e.j.s.= AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 313896 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Start Jetty (original configured port=3D0) [junit4] 2> 313897 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 313897 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c45500= 56e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS [junit4] 2> 314026 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 314027 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= session No SessionScavenger set, using defaults [junit4] 2> 314027 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= session node0 Scavenging every 600000ms [junit4] 2> 314038 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@3799a8ca{/solr,null,= AVAILABLE} [junit4] 2> 314058 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 314058 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= session No SessionScavenger set, using defaults [junit4] 2> 314058 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= session node0 Scavenging every 660000ms [junit4] 2> 314078 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= h.ContextHandler Started o.e.j.s.ServletContextHandler@6d2fbf78{/solr,null,= AVAILABLE} [junit4] 2> 314095 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@3db2d3b0{HTTP/1.1,[http/1.1, h2c]= }{127.0.0.1:36164} [junit4] 2> 314095 INFO (jetty-launcher-327-thread-1) [ ] o.e.j.s.= Server Started @314430ms [junit4] 2> 314095 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D3616= 4} [junit4] 2> 314096 ERROR (jetty-launcher-327-thread-1) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 314096 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.s.= SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLogge= rFactory [junit4] 2> 314096 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 versio= n 9.0.0 [junit4] 2> 314096 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 314096 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 314096 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-07T19:02:38.560516= Z [junit4] 2> 314118 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= AbstractConnector Started ServerConnector@1bf50011{HTTP/1.1,[http/1.1, h2c]= }{127.0.0.1:37606} [junit4] 2> 314118 INFO (jetty-launcher-327-thread-2) [ ] o.e.j.s.= Server Started @314452ms [junit4] 2> 314118 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.c.= s.e.JettySolrRunner Jetty properties: {hostContext=3D/solr, hostPort=3D3760= 6} [junit4] 2> 314167 ERROR (jetty-launcher-327-thread-2) [ ] o.a.s.u.= StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missin= g or incomplete. [junit4] 2> 314167 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.s.= SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLogge= rFactory [junit4] 2> 314167 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.s.= SolrDispatchFilter ___ _ Welcome to Apache Solr=E2=84=A2 versio= n 9.0.0 [junit4] 2> 314168 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.s.= SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 314168 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.s.= SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 314168 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.s.= SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-07T19:02:38.632307= Z [junit4] 2> 314204 INFO (zkConnectionManagerCallback-329-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 314242 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 314297 INFO (zkConnectionManagerCallback-331-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 314334 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.s.= SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 315045 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.h.= c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker = [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Dtrue] [junit4] 2> 315080 WARN (jetty-launcher-327-thread-2) [ ] o.e.j.u.= s.S.config No Client EndPointIdentificationAlgorithm configured for SslCont= extFactory@2e00e745[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 315153 WARN (jetty-launcher-327-thread-2) [ ] o.e.j.u.= s.S.config No Client EndPointIdentificationAlgorithm configured for SslCont= extFactory@23639154[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 315154 INFO (jetty-launcher-327-thread-2) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:43032/solr [junit4] 2> 315164 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.h.= c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker = [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Dtrue] [junit4] 2> 315211 WARN (jetty-launcher-327-thread-1) [ ] o.e.j.u.= s.S.config No Client EndPointIdentificationAlgorithm configured for SslCont= extFactory@4c8fb52[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 315225 INFO (zkConnectionManagerCallback-339-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 315262 WARN (jetty-launcher-327-thread-1) [ ] o.e.j.u.= s.S.config No Client EndPointIdentificationAlgorithm configured for SslCont= extFactory@3617da3f[provider=3Dnull,keyStore=3Dnull,trustStore=3Dnull] [junit4] 2> 315274 INFO (jetty-launcher-327-thread-1) [ ] o.a.s.c.= ZkContainer Zookeeper client=3D127.0.0.1:43032/solr [junit4] 2> 315274 INFO (zkConnectionManagerCallback-344-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 315280 INFO (zkConnectionManagerCallback-347-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 315299 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ = ] o.a.z.s.NIOServerCnxn Unable to read additional data from client session= id 0x10937efa8bc0007, likely client has closed socket [junit4] 2> 315476 INFO (zkConnectionManagerCallback-349-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 315573 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 1= 27.0.0.1:37606_solr [junit4] 2> 315607 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.c.Overseer Overseer (id=3D74652371295928326-127.0.0.1:3= 7606_solr-n_0000000000) starting [junit4] 2> 315711 INFO (zkConnectionManagerCallback-358-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 315930 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1= :43032/solr ready [junit4] 2> 315934 INFO (OverseerStateUpdate-74652371295928326-127.0.= 0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] o.a.s.c.Overseer = Starting to work on the main queue : 127.0.0.1:37606_solr [junit4] 2> 315966 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:37606_solr [junit4] 2> 316023 INFO (zkCallback-357-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 316023 INFO (zkCallback-343-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 316262 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keepin= g metrics history in memory. [junit4] 2> 316555 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37606.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@6c2625ca [junit4] 2> 316627 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... = (0) -> (1) [junit4] 2> 316701 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37606.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@6c2625ca [junit4] 2> 316701 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37606.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6c2625ca [junit4] 2> 316703 INFO (jetty-launcher-327-thread-2) [n:127.0.0.1:37= 606_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D353B= -001/tempDir-001/node1/. [junit4] 2> 316918 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.ZkController Publish node=3D127.0.0.1:36164_solr as D= OWN [junit4] 2> 316992 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient ca= che for 2147483647 transient cores [junit4] 2> 316992 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live= _nodes/127.0.0.1:36164_solr [junit4] 2> 317048 INFO (zkCallback-343-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 317048 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 317060 INFO (zkCallback-357-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 317664 INFO (zkConnectionManagerCallback-364-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 317670 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... = (0) -> (2) [junit4] 2> 317671 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1= :43032/solr ready [junit4] 2> 317685 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keepin= g metrics history in memory. [junit4] 2> 317833 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36164.solr= .node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.Jm= xMBeanServer@6c2625ca [junit4] 2> 317907 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36164.solr= .jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxM= BeanServer@6c2625ca [junit4] 2> 317924 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36164.solr= .jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.= JmxMBeanServer@6c2625ca [junit4] 2> 317925 INFO (jetty-launcher-327-thread-1) [n:127.0.0.1:36= 164_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions undern= eath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/bu= ild/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D353B= -001/tempDir-001/node2/. [junit4] 2> 318300 INFO (zkConnectionManagerCallback-370-thread-1) [ = ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 318306 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKe= eper... (0) -> (2) [junit4] 2> 318342 INFO (SUITE-AliasIntegrationTest-seed#[DD8438EB6C3= D353B]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 1= 27.0.0.1:43032/solr ready [junit4] 2> 318476 INFO (TEST-AliasIntegrationTest.testModifyProperti= esCAR-seed#[DD8438EB6C3D353B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test= ModifyPropertiesCAR [junit4] 2> 318731 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus wi= th params action=3DCLUSTERSTATUS&wt=3Djavabin&version=3D2 and sendToOCPQueu= e=3Dtrue [junit4] 2> 318739 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{action=3DCLUSTERSTATUS&wt=3Djavabin&version=3D2} status=3D0 QTime=3D= 7 [junit4] 2> 318788 INFO (qtp2032433449-771) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams collection.configName=3Dconf&name=3Dcollection1meta&nrtReplicas=3D1&act= ion=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtr= ue [junit4] 2> 318835 INFO (OverseerThreadFactory-470-thread-1-processin= g-n:127.0.0.1:37606_solr) [n:127.0.0.1:37606_solr ] o.a.s.c.a.c.CreateCo= llectionCmd Create collection collection1meta [junit4] 2> 319395 INFO (OverseerStateUpdate-74652371295928326-127.0.= 0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] o.a.s.c.o.SliceMu= tator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1meta", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1meta_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:37606/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 319540 INFO (OverseerStateUpdate-74652371295928326-127.0.= 0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] o.a.s.c.o.SliceMu= tator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1meta", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection1meta_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:36164/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 319827 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core cr= eate command qt=3D/admin/cores&coreNodeName=3Dcore_node4&collection.configN= ame=3Dconf&newCollection=3Dtrue&name=3Dcollection1meta_shard2_replica_n2&ac= tion=3DCREATE&numShards=3D2&collection=3Dcollection1meta&shard=3Dshard2&wt= =3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 319848 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core cre= ate command qt=3D/admin/cores&coreNodeName=3Dcore_node3&collection.configNa= me=3Dconf&newCollection=3Dtrue&name=3Dcollection1meta_shard1_replica_n1&act= ion=3DCREATE&numShards=3D2&collection=3Dcollection1meta&shard=3Dshard1&wt= =3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 319849 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault= Allocating transient cache for 2147483647 transient cores [junit4] 2> 321014 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 321018 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 321141 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.s.IndexSchema [collection1meta_shard2_replica_n2] Schema name=3Dmini= mal [junit4] 2> 321143 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 321143 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n2= ' using configuration from collection collection1meta, trusted=3Dtrue [junit4] 2> 321164 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=3Dminim= al [junit4] 2> 321177 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36164.solr.core.collect= ion1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.rep= lica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c2625c= a [junit4] 2> 321177 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.SolrCore [[collection1meta_shard2_replica_n2] ] Opening new SolrCo= re at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/= build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D35= 3B-001/tempDir-001/node2/collection1meta_shard2_replica_n2], dataDir=3D[/ho= me/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr= -core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D353B-001/tem= pDir-001/node2/./collection1meta_shard2_replica_n2/data/] [junit4] 2> 321179 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 321179 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1'= using configuration from collection collection1meta, trusted=3Dtrue [junit4] 2> 321180 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37606.solr.core.collecti= on1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.repl= ica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c2625ca [junit4] 2> 321180 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCor= e at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/b= uild/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D353= B-001/tempDir-001/node1/collection1meta_shard1_replica_n1], dataDir=3D[/hom= e/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-= core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D353B-001/temp= Dir-001/node1/./collection1meta_shard1_replica_n1/data/] [junit4] 2> 321700 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.updat= e.UpdateLog [junit4] 2> 321700 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel= =3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D6= 5536 [junit4] 2> 321701 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 321701 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 321736 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.s.SolrIndexSearcher Opening [Searcher@541d7b01[collection1meta_shard1= _replica_n1] main] [junit4] 2> 321738 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase= : /configs/conf [junit4] 2> 321738 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using= ZooKeeperStorageIO:path=3D/configs/conf [junit4] 2> 321739 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 321739 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.u.UpdateLog Could not find max version in index or recent updates, us= ing new clock 1632901022302076928 [junit4] 2> 321809 INFO (searcherExecutor-480-thread-1-processing-n:1= 27.0.0.1:37606_solr x:collection1meta_shard1_replica_n1 c:collection1meta s= :shard1 r:core_node3) [n:127.0.0.1:37606_solr c:collection1meta s:shard1 r:= core_node3 x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collectio= n1meta_shard1_replica_n1] Registered new searcher Searcher@541d7b01[collect= ion1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirecto= ryReader())} [junit4] 2> 321810 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1= meta/terms/shard1 to Terms{values=3D{core_node3=3D0}, version=3D0} [junit4] 2> 321810 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collect= ions/collection1meta/leaders/shard1 [junit4] 2> 321826 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 321826 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 321826 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37606/solr/collectio= n1meta_shard1_replica_n1/ [junit4] 2> 321827 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 321827 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.SyncStrategy http://127.0.0.1:37606/solr/collection1meta_shard1_rep= lica_n1/ has no replicas [junit4] 2> 321827 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /c= ollections/collection1meta/leaders/shard1/leader after winning as /collecti= ons/collection1meta/leader_elect/shard1/election/74652371295928326-core_nod= e3-n_0000000000 [junit4] 2> 321829 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37= 606/solr/collection1meta_shard1_replica_n1/ shard1 [junit4] 2> 321890 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.upda= te.UpdateLog [junit4] 2> 321890 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel= =3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D6= 5536 [junit4] 2> 321892 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 321892 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 321907 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.s.SolrIndexSearcher Opening [Searcher@3d56ba2d[collection1meta_shard= 2_replica_n2] main] [junit4] 2> 321908 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBas= e: /configs/conf [junit4] 2> 321909 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json usin= g ZooKeeperStorageIO:path=3D/configs/conf [junit4] 2> 321909 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 321910 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.u.UpdateLog Could not find max version in index or recent updates, u= sing new clock 1632901022480334848 [junit4] 2> 321980 INFO (zkCallback-343-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 321980 INFO (zkCallback-343-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322013 INFO (searcherExecutor-479-thread-1-processing-n:1= 27.0.0.1:36164_solr x:collection1meta_shard2_replica_n2 c:collection1meta s= :shard2 r:core_node4) [n:127.0.0.1:36164_solr c:collection1meta s:shard2 r:= core_node4 x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [collectio= n1meta_shard2_replica_n2] Registered new searcher Searcher@3d56ba2d[collect= ion1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirecto= ryReader())} [junit4] 2> 322014 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection= 1meta/terms/shard2 to Terms{values=3D{core_node4=3D0}, version=3D0} [junit4] 2> 322014 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collec= tions/collection1meta/leaders/shard2 [junit4] 2> 322028 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 322030 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr c:= collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1] = o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{qt= =3D/admin/cores&coreNodeName=3Dcore_node3&collection.configName=3Dconf&newC= ollection=3Dtrue&name=3Dcollection1meta_shard1_replica_n1&action=3DCREATE&n= umShards=3D2&collection=3Dcollection1meta&shard=3Dshard1&wt=3Djavabin&versi= on=3D2&replicaType=3DNRT} status=3D0 QTime=3D2201 [junit4] 2> 322063 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 322063 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 322063 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36164/solr/collecti= on1meta_shard2_replica_n2/ [junit4] 2> 322063 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 322064 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.SyncStrategy http://127.0.0.1:36164/solr/collection1meta_shard2_re= plica_n2/ has no replicas [junit4] 2> 322064 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /= collections/collection1meta/leaders/shard2/leader after winning as /collect= ions/collection1meta/leader_elect/shard2/election/74652371295928328-core_no= de4-n_0000000000 [junit4] 2> 322119 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:3= 6164/solr/collection1meta_shard2_replica_n2/ shard2 [junit4] 2> 322324 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322324 INFO (zkCallback-343-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322324 INFO (zkCallback-343-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322324 INFO (zkCallback-343-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322324 INFO (zkCallback-348-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322352 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 322354 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr c= :collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2]= o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{q= t=3D/admin/cores&coreNodeName=3Dcore_node4&collection.configName=3Dconf&new= Collection=3Dtrue&name=3Dcollection1meta_shard2_replica_n2&action=3DCREATE&= numShards=3D2&collection=3Dcollection1meta&shard=3Dshard2&wt=3Djavabin&vers= ion=3D2&replicaType=3DNRT} status=3D0 QTime=3D2528 [junit4] 2> 322454 INFO (qtp2032433449-771) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for a= t most 45 seconds. Check all shard replicas [junit4] 2> 322563 INFO (zkCallback-348-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322563 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322563 INFO (zkCallback-343-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322563 INFO (zkCallback-343-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322563 INFO (zkCallback-343-thread-4) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322571 INFO (zkCallback-348-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 322580 INFO (qtp2032433449-771) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{collection.configName=3Dconf&name=3Dcollection1meta&nrtReplicas=3D1= &action=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D3791 [junit4] 2> 322644 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams collection.configName=3Dconf&name=3Dcollection2meta&nrtReplicas=3D1&act= ion=3DCREATE&numShards=3D1&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtr= ue [junit4] 2> 322820 INFO (OverseerThreadFactory-470-thread-2-processin= g-n:127.0.0.1:37606_solr) [n:127.0.0.1:37606_solr ] o.a.s.c.a.c.CreateCo= llectionCmd Create collection collection2meta [junit4] 2> 322854 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 323199 INFO (OverseerStateUpdate-74652371295928326-127.0.= 0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] o.a.s.c.o.SliceMu= tator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection2meta", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection2meta_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:36164/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 323464 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core cr= eate command qt=3D/admin/cores&coreNodeName=3Dcore_node2&collection.configN= ame=3Dconf&newCollection=3Dtrue&name=3Dcollection2meta_shard1_replica_n1&ac= tion=3DCREATE&numShards=3D1&collection=3Dcollection2meta&shard=3Dshard1&wt= =3Djavabin&version=3D2&replicaType=3DNRT [junit4] 2> 324643 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 324717 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.s.IndexSchema [collection2meta_shard1_replica_n1] Schema name=3Dmini= mal [junit4] 2> 324732 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 324732 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.CoreContainer Creating SolrCore 'collection2meta_shard1_replica_n1= ' using configuration from collection collection2meta, trusted=3Dtrue [junit4] 2> 324733 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36164.solr.core.collect= ion2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.rep= lica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6c2625c= a [junit4] 2> 324733 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.SolrCore [[collection2meta_shard1_replica_n1] ] Opening new SolrCo= re at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/= build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D35= 3B-001/tempDir-001/node2/collection2meta_shard1_replica_n1], dataDir=3D[/ho= me/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr= -core/test/J1/temp/solr.cloud.AliasIntegrationTest_DD8438EB6C3D353B-001/tem= pDir-001/node2/./collection2meta_shard1_replica_n1/data/] [junit4] 2> 325238 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.upda= te.UpdateLog [junit4] 2> 325238 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3Dnull defaultSyncLevel= =3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D6= 5536 [junit4] 2> 325239 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 325239 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 325254 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.s.SolrIndexSearcher Opening [Searcher@4c657423[collection2meta_shard= 1_replica_n1] main] [junit4] 2> 325256 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBas= e: /configs/conf [junit4] 2> 325256 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json usin= g ZooKeeperStorageIO:path=3D/configs/conf [junit4] 2> 325273 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 325273 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.u.UpdateLog Could not find max version in index or recent updates, u= sing new clock 1632901026007744512 [junit4] 2> 325276 INFO (searcherExecutor-489-thread-1-processing-n:1= 27.0.0.1:36164_solr x:collection2meta_shard1_replica_n1 c:collection2meta s= :shard1 r:core_node2) [n:127.0.0.1:36164_solr c:collection2meta s:shard1 r:= core_node2 x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [collectio= n2meta_shard1_replica_n1] Registered new searcher Searcher@4c657423[collect= ion2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirecto= ryReader())} [junit4] 2> 325307 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection= 2meta/terms/shard1 to Terms{values=3D{core_node2=3D0}, version=3D0} [junit4] 2> 325307 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collec= tions/collection2meta/leaders/shard1 [junit4] 2> 325327 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 325327 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 325327 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36164/solr/collecti= on2meta_shard1_replica_n1/ [junit4] 2> 325328 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 325328 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.SyncStrategy http://127.0.0.1:36164/solr/collection2meta_shard1_re= plica_n1/ has no replicas [junit4] 2> 325328 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /= collections/collection2meta/leaders/shard1/leader after winning as /collect= ions/collection2meta/leader_elect/shard1/election/74652371295928328-core_no= de2-n_0000000000 [junit4] 2> 325348 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:3= 6164/solr/collection2meta_shard1_replica_n1/ shard1 [junit4] 2> 325500 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection2meta/state.json] for collectio= n [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 325500 INFO (zkCallback-348-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection2meta/state.json] for collectio= n [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 325503 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 325518 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1]= o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{q= t=3D/admin/cores&coreNodeName=3Dcore_node2&collection.configName=3Dconf&new= Collection=3Dtrue&name=3Dcollection2meta_shard1_replica_n1&action=3DCREATE&= numShards=3D1&collection=3Dcollection2meta&shard=3Dshard1&wt=3Djavabin&vers= ion=3D2&replicaType=3DNRT} status=3D0 QTime=3D2054 [junit4] 2> 325569 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for a= t most 45 seconds. Check all shard replicas [junit4] 2> 325632 INFO (zkCallback-348-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection2meta/state.json] for collectio= n [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 325632 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection2meta/state.json] for collectio= n [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 325632 INFO (zkCallback-348-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection2meta/state.json] for collectio= n [collection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 325655 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{collection.configName=3Dconf&name=3Dcollection2meta&nrtReplicas=3D1= &action=3DCREATE&numShards=3D1&wt=3Djavabin&version=3D2} status=3D0 QTime= =3D3010 [junit4] 2> 325700 INFO (TEST-AliasIntegrationTest.testModifyProperti= esCAR-seed#[DD8438EB6C3D353B]) [ ] o.a.s.c.SolrCloudTestCase active repl= ica count: 2 expected replica count: 2 [junit4] 2> 325700 INFO (watches-367-thread-1) [ ] o.a.s.c.SolrClo= udTestCase active replica count: 2 expected replica count: 2 [junit4] 2> 325701 INFO (TEST-AliasIntegrationTest.testModifyProperti= esCAR-seed#[DD8438EB6C3D353B]) [ ] o.a.s.c.SolrCloudTestCase active repl= ica count: 1 expected replica count: 1 [junit4] 2> 325747 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with = params name=3DtestModifyPropertiesCAR&action=3DALIASPROP&property.foo=3Dbaz= &property.bar=3Dbam&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 325966 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 326041 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{name=3DtestModifyPropertiesCAR&action=3DALIASPROP&property.foo=3Dba= z&property.bar=3Dbam&wt=3Djavabin&version=3D2} status=3D0 QTime=3D293 [junit4] 2> 326079 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with = params name=3DtestModifyPropertiesCAR&action=3DALIASPROP&property.foo=3D&wt= =3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 326192 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 326244 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{name=3DtestModifyPropertiesCAR&action=3DALIASPROP&property.foo=3D&w= t=3Djavabin&version=3D2} status=3D0 QTime=3D164 [junit4] 2> 326248 INFO (qtp2032433449-771) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with = params name=3DtestModifyPropertiesCAR&action=3DALIASPROP&wt=3Djavabin&versi= on=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 326440 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 326606 INFO (qtp2032433449-771) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{name=3DtestModifyPropertiesCAR&action=3DALIASPROP&wt=3Djavabin&vers= ion=3D2} status=3D0 QTime=3D358 [junit4] 2> 326622 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with = params name=3DtestModifyPropertiesCAR&action=3DALIASPROP&property.foo=3D+&w= t=3Djavabin&version=3D2 and sendToOCPQueue=3Dtrue [junit4] 2> 326768 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 326794 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{name=3DtestModifyPropertiesCAR&action=3DALIASPROP&property.foo=3D+&= wt=3Djavabin&version=3D2} status=3D0 QTime=3D184 [junit4] 2> 326804 INFO (TEST-AliasIntegrationTest.testModifyProperti= esCAR-seed#[DD8438EB6C3D353B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMo= difyPropertiesCAR [junit4] 2> 326866 INFO (TEST-AliasIntegrationTest.testModifyProperti= esCAR-seed#[DD8438EB6C3D353B]) [ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (0) -> (2) [junit4] 2> 326894 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with par= ams name=3Dcollection2meta&action=3DDELETE&wt=3Djavabin&version=3D2 and sen= dToOCPQueue=3Dtrue [junit4] 2> 326940 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 327015 INFO (OverseerThreadFactory-470-thread-5-processin= g-n:127.0.0.1:37606_solr) [n:127.0.0.1:37606_solr ] o.a.s.c.a.c.Overseer= CollectionMessageHandler Executing Collection Cmd=3Daction=3DUNLOAD&deleteI= nstanceDir=3Dtrue&deleteDataDir=3Dtrue&deleteMetricsHistory=3Dtrue, asyncId= =3Dnull [junit4] 2> 327033 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing me= tric reporters for registry=3Dsolr.core.collection2meta.shard1.replica_n1, = tag=3Dnull [junit4] 2> 327033 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing re= porter [org.apache.solr.metrics.reporters.SolrJmxReporter@2110fe6b: rootNam= e =3D solr_36164, domain =3D solr.core.collection2meta.shard1.replica_n1, s= ervice url =3D null, agent id =3D null] for registry solr.core.collection2m= eta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@1dce40e4 [junit4] 2> 327272 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.c.SolrCore [collection2meta_sh= ard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@5ef359b5 [junit4] 2> 327273 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing me= tric reporters for registry=3Dsolr.core.collection2meta.shard1.replica_n1, = tag=3D5ef359b5 [junit4] 2> 327273 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing me= tric reporters for registry=3Dsolr.collection.collection2meta.shard1.leader= , tag=3D5ef359b5 [junit4] 2> 327381 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful upda= te of terms at /collections/collection2meta/terms/shard1 to Terms{values=3D= {}, version=3D1} [junit4] 2> 327432 INFO (qtp2032433449-772) [n:127.0.0.1:36164_solr = x:collection2meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{deleteInstanceDir=3Dtrue&deleteMetric= sHistory=3Dtrue&core=3Dcollection2meta_shard1_replica_n1&qt=3D/admin/cores&= deleteDataDir=3Dtrue&action=3DUNLOAD&wt=3Djavabin&version=3D2} status=3D0 Q= Time=3D399 [junit4] 2> 327581 INFO (zkCallback-348-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection2meta/state.json] for collection [c= ollection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 327581 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection2meta/state.json] for collection [c= ollection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 327581 INFO (zkCallback-348-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection2meta/state.json] for collection [c= ollection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 327581 INFO (zkCallback-343-thread-4) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection2meta/state.json] for collection [c= ollection2meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 327804 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{name=3Dcollection2meta&action=3DDELETE&wt=3Djavabin&version=3D2} st= atus=3D0 QTime=3D910 [junit4] 2> 327839 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with par= ams name=3Dcollection1meta&action=3DDELETE&wt=3Djavabin&version=3D2 and sen= dToOCPQueue=3Dtrue [junit4] 2> 327912 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000012 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 327912 INFO (OverseerThreadFactory-470-thread-5-processin= g-n:127.0.0.1:37606_solr) [n:127.0.0.1:37606_solr ] o.a.s.c.a.c.Overseer= CollectionMessageHandler Executing Collection Cmd=3Daction=3DUNLOAD&deleteI= nstanceDir=3Dtrue&deleteDataDir=3Dtrue&deleteMetricsHistory=3Dtrue, asyncId= =3Dnull [junit4] 2> 327948 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing me= tric reporters for registry=3Dsolr.core.collection1meta.shard2.replica_n2, = tag=3Dnull [junit4] 2> 327948 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing re= porter [org.apache.solr.metrics.reporters.SolrJmxReporter@78af354b: rootNam= e =3D solr_36164, domain =3D solr.core.collection1meta.shard2.replica_n2, s= ervice url =3D null, agent id =3D null] for registry solr.core.collection1m= eta.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@1631a0e [junit4] 2> 327996 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing met= ric reporters for registry=3Dsolr.core.collection1meta.shard1.replica_n1, t= ag=3Dnull [junit4] 2> 327997 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing rep= orter [org.apache.solr.metrics.reporters.SolrJmxReporter@71a3decf: rootName= =3D solr_37606, domain =3D solr.core.collection1meta.shard1.replica_n1, se= rvice url =3D null, agent id =3D null] for registry solr.core.collection1me= ta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@7116f9f6 [junit4] 2> 328063 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.c.SolrCore [collection1meta_sh= ard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@1ed9b290 [junit4] 2> 328064 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing me= tric reporters for registry=3Dsolr.core.collection1meta.shard2.replica_n2, = tag=3D1ed9b290 [junit4] 2> 328064 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing me= tric reporters for registry=3Dsolr.collection.collection1meta.shard2.leader= , tag=3D1ed9b290 [junit4] 2> 328130 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful upda= te of terms at /collections/collection1meta/terms/shard2 to Terms{values=3D= {}, version=3D1} [junit4] 2> 328132 INFO (qtp2032433449-774) [n:127.0.0.1:36164_solr = x:collection1meta_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{deleteInstanceDir=3Dtrue&deleteMetric= sHistory=3Dtrue&core=3Dcollection1meta_shard2_replica_n2&qt=3D/admin/cores&= deleteDataDir=3Dtrue&action=3DUNLOAD&wt=3Djavabin&version=3D2} status=3D0 Q= Time=3D184 [junit4] 2> 328200 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.c.SolrCore [collection1meta_sha= rd1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@52b2bbe2 [junit4] 2> 328200 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing met= ric reporters for registry=3Dsolr.core.collection1meta.shard1.replica_n1, t= ag=3D52b2bbe2 [junit4] 2> 328200 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing met= ric reporters for registry=3Dsolr.collection.collection1meta.shard1.leader,= tag=3D52b2bbe2 [junit4] 2> 328239 INFO (zkCallback-348-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328240 INFO (zkCallback-343-thread-4) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328240 INFO (zkCallback-348-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328240 INFO (zkCallback-348-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328240 INFO (zkCallback-343-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDataChanged path:/collections/collection1meta/state.json] for collectio= n [collection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328331 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful updat= e of terms at /collections/collection1meta/terms/shard1 to Terms{values=3D{= }, version=3D1} [junit4] 2> 328422 INFO (qtp223808062-764) [n:127.0.0.1:37606_solr = x:collection1meta_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp= =3Dnull path=3D/admin/cores params=3D{deleteInstanceDir=3Dtrue&deleteMetric= sHistory=3Dtrue&core=3Dcollection1meta_shard1_replica_n1&qt=3D/admin/cores&= deleteDataDir=3Dtrue&action=3DUNLOAD&wt=3Djavabin&version=3D2} status=3D0 Q= Time=3D426 [junit4] 2> 328677 INFO (zkCallback-343-thread-3) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection1meta/state.json] for collection [c= ollection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328677 INFO (zkCallback-343-thread-2) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection1meta/state.json] for collection [c= ollection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328677 INFO (zkCallback-343-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: [WatchedEvent state:SyncConnected type:= NodeDeleted path:/collections/collection1meta/state.json] for collection [c= ollection1meta] has occurred - updating... (live nodes size: [2]) [junit4] 2> 328821 INFO (qtp2032433449-761) [n:127.0.0.1:36164_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections pa= rams=3D{name=3Dcollection1meta&action=3DDELETE&wt=3Djavabin&version=3D2} st= atus=3D0 QTime=3D981 [junit4] 2> 329432 INFO (TEST-AliasIntegrationTest.testDeleteAliasWit= hExistingCollectionName-seed#[DD8438EB6C3D353B]) [ ] o.a.s.SolrTestCaseJ= 4 ###Starting testDeleteAliasWithExistingCollectionName [junit4] 2> 329503 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus wi= th params action=3DCLUSTERSTATUS&wt=3Djavabin&version=3D2 and sendToOCPQueu= e=3Dtrue [junit4] 2> 329505 INFO (qtp223808062-766) [n:127.0.0.1:37606_solr = ] o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/collections par= ams=3D{action=3DCLUSTERSTATUS&wt=3Djavabin&version=3D2} status=3D0 QTime=3D= 2 [junit4] 2> 329597 INFO (qtp2032433449-771) [n:127.0.0.1:36164_solr = ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with par= ams collection.configName=3Dconf&name=3Dcollection_old&nrtReplicas=3D1&acti= on=3DCREATE&numShards=3D2&wt=3Djavabin&version=3D2 and sendToOCPQueue=3Dtru= e [junit4] 2> 329651 INFO (OverseerThreadFactory-470-thread-5-processin= g-n:127.0.0.1:37606_solr) [n:127.0.0.1:37606_solr ] o.a.s.c.a.c.CreateCo= llectionCmd Create collection collection_old [junit4] 2> 329750 INFO (OverseerCollectionConfigSetProcessor-7465237= 1295928326-127.0.0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] = o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work= /qnr-0000000014 doesn't exist. Requestor may have disconnected from ZooKee= per [junit4] 2> 330329 INFO (OverseerStateUpdate-74652371295928326-127.0.= 0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] o.a.s.c.o.SliceMu= tator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_old", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection_old_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:37606/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 330420 INFO (OverseerStateUpdate-74652371295928326-127.0.= 0.1:37606_solr-n_0000000000) [n:127.0.0.1:37606_solr ] o.a.s.c.o.SliceMu= tator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection_old", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection_old_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:36164/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"}=20 [junit4] 2> 330616 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr = x:collection_old_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core cre= ate command qt=3D/admin/cores&coreNodeName=3Dcore_node4&collection.configNa= me=3Dconf&newCollection=3Dtrue&name=3Dcollection_old_shard2_replica_n2&acti= on=3DCREATE&numShards=3D2&collection=3Dcollection_old&shard=3Dshard2&wt=3Dj= avabin&version=3D2&replicaType=3DNRT [junit4] 2> 330618 INFO (qtp223808062-767) [n:127.0.0.1:37606_solr = x:collection_old_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core crea= te command qt=3D/admin/cores&coreNodeName=3Dcore_node3&collection.configNam= e=3Dconf&newCollection=3Dtrue&name=3Dcollection_old_shard1_replica_n1&actio= n=3DCREATE&numShards=3D2&collection=3Dcollection_old&shard=3Dshard1&wt=3Dja= vabin&version=3D2&replicaType=3DNRT [junit4] 2> 331677 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o= .a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 331869 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o= .a.s.s.IndexSchema [collection_old_shard2_replica_n2] Schema name=3Dminimal [junit4] 2> 331871 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] o= .a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 331871 INFO (qtp2032433449-770) [n:127.0.0.1:36164_solr c= :collection_old s:shard2 r:core_node4 x:collectio [...truncated too long message...] cene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length i= s 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file =3D /home/jenkins/jenkins-slave= /workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-= Tests-master/solr/null1635160247 [copy] Copying 239 files to /home/jenkins/jenkins-slave/workspace/Luce= ne-Solr-Tests-master/solr/null1635160247 [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene= -Solr-Tests-master/solr/null1635160247 check-working-copy: [ivy:cachepath] :: resolving dependencies :: #;working@lucene2-us-west.apac= he.org [ivy:cachepath] =09confs: [default] [ivy:cachepath] =09found org.eclipse.jgit#org.eclipse.jgit;5.3.0.2019031308= 48-r in public [ivy:cachepath] =09found com.jcraft#jsch;0.1.54 in public [ivy:cachepath] =09found com.jcraft#jzlib;1.1.1 in public [ivy:cachepath] =09found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] =09found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] =09found org.bouncycastle#bcpg-jdk15on;1.60 in public [ivy:cachepath] =09found org.bouncycastle#bcprov-jdk15on;1.60 in public [ivy:cachepath] =09found org.bouncycastle#bcpkix-jdk15on;1.60 in public [ivy:cachepath] =09found org.slf4j#slf4j-nop;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 64ms :: artifacts dl 5ms =09--------------------------------------------------------------------- =09| | modules || artifacts | =09| conf | number| search|dwnlded|evicted|| number|dwnlded| =09--------------------------------------------------------------------- =09| default | 9 | 0 | 0 | 0 || 9 | 0 | =09--------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 250 minutes 2 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 =09at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847) =09at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726) =09at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707) =09at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2 =09=09at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741= ) =09=09at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest= .java:357) =09=09at hudson.remoting.Channel.call(Channel.java:955) =09=09at hudson.FilePath.act(FilePath.java:1072) =09=09at hudson.FilePath.act(FilePath.java:1061) =09=09at hudson.FilePath.validateAntFileMask(FilePath.java:2705) =09=09at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) =09=09at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatib= ilityLayer.java:81) =09=09at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) =09=09at hudson.model.AbstractBuild$AbstractBuildExecution.perform(Abstract= Build.java:744) =09=09at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildS= teps(AbstractBuild.java:690) =09=09at hudson.model.Build$BuildExecution.post2(Build.java:186) =09=09at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBui= ld.java:635) =09=09at hudson.model.Run.execute(Run.java:1835) =09=09at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) =09=09at hudson.model.ResourceController.execute(ResourceController.java:97= ) =09=09at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException =09at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088) =09at hudson.remoting.UserRequest.perform(UserRequest.java:212) =09at hudson.remoting.UserRequest.perform(UserRequest.java:54) =09at hudson.remoting.Request$2.run(Request.java:369) =09at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecut= orService.java:72) =09at java.util.concurrent.FutureTask.run(FutureTask.java:266) =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.= java:1142) =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor= .java:617) =09at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no = matches found within 10000 =09at hudson.FilePath.act(FilePath.java:1074) =09at hudson.FilePath.act(FilePath.java:1061) =09at hudson.FilePath.validateAntFileMask(FilePath.java:2705) =09at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) =09at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibili= tyLayer.java:81) =09at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) =09at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBui= ld.java:744) =09at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildStep= s(AbstractBuild.java:690) =09at hudson.model.Build$BuildExecution.post2(Build.java:186) =09at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.= java:635) =09at hudson.model.Run.execute(Run.java:1835) =09at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) =09at hudson.model.ResourceController.execute(ResourceController.java:97) =09at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**= /hs_err_pid*". Configuration error? Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTA= BLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) ------=_Part_8830_1757466350.1557264345794 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_8830_1757466350.1557264345794--