From dev-return-354537-archive-asf-public=cust-asf.ponee.io@lucene.apache.org Wed May 1 07:47:05 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 9AD40180629 for ; Wed, 1 May 2019 09:47:04 +0200 (CEST) Received: (qmail 12756 invoked by uid 500); 1 May 2019 07:47:02 -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 12746 invoked by uid 99); 1 May 2019 07:47:02 -0000 Received: from Unknown (HELO mailrelay1-lw-us.apache.org) (10.10.3.159) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 01 May 2019 07:47:02 +0000 Received: from jenkins02.apache.org (jenkins02.apache.org [195.201.213.130]) by mailrelay1-lw-us.apache.org (ASF Mail Server at mailrelay1-lw-us.apache.org) with ESMTP id 9694C19C2 for ; Wed, 1 May 2019 07:47:01 +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 BAB3633E0199 for ; Wed, 1 May 2019 07:47:00 +0000 (UTC) Date: Wed, 1 May 2019 07:46:08 +0000 (UTC) From: Apache Jenkins Server To: dev@lucene.apache.org Message-ID: <528090730.6665.1556696820766.JavaMail.jenkins@jenkins02> In-Reply-To: <148592928.6447.1556644953343.JavaMail.jenkins@jenkins02> References: <148592928.6447.1556644953343.JavaMail.jenkins@jenkins02> Subject: [JENKINS] Lucene-Solr-BadApples-Tests-8.x - Build # 91 - Still Failing MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_6664_1162174080.1556696819786" X-Jenkins-Job: Lucene-Solr-BadApples-Tests-8.x X-Jenkins-Result: FAILURE ------=_Part_6664_1162174080.1556696819786 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-8.x/91/ 3 tests failed. FAILED: org.apache.solr.update.PeerSyncTest.test Error Message: Unexpected exception type, expected SolrException but got org.apache.solr.c= lient.solrj.SolrServerException: Timeout occurred while waiting response fr= om server at: http://127.0.0.1:46572/nk_z/a/collection1 Stack Trace: junit.framework.AssertionFailedError: Unexpected exception type, expected S= olrException but got org.apache.solr.client.solrj.SolrServerException: Time= out occurred while waiting response from server at: http://127.0.0.1:46572/= nk_z/a/collection1 =09at __randomizedtesting.SeedInfo.seed([3305A115FE94BB4A:BB519ECF5068D6B2]= :0) =09at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.jav= a:2700) =09at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.jav= a:2689) =09at org.apache.solr.update.PeerSyncTest.test(PeerSyncTest.java:279) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java: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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:1054) =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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurr= ed while waiting response from server at: http://127.0.0.1:46572/nk_z/a/col= lection1 =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:660) =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.SolrRequest.process(SolrRequest.java:207= ) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224= ) =09at org.apache.solr.BaseDistributedSearchTestCase.add(BaseDistributedSear= chTestCase.java:576) =09at org.apache.solr.update.PeerSyncTest.lambda$test$0(PeerSyncTest.java:2= 81) =09at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.jav= a:2695) =09... 43 more Caused by: java.net.SocketTimeoutException: Read timed out =09at java.net.SocketInputStream.socketRead0(Native Method) =09at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) =09at java.net.SocketInputStream.read(SocketInputStream.java:171) =09at java.net.SocketInputStream.read(SocketInputStream.java:141) =09at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInpu= tBufferImpl.java:137) =09at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInpu= tBufferImpl.java:153) =09at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputB= ufferImpl.java:282) =09at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(Default= HttpResponseParser.java:138) =09at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(Default= HttpResponseParser.java:56) =09at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessagePa= rser.java:259) =09at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHead= er(DefaultBHttpClientConnection.java:163) =09at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy= .java:165) =09at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRe= questExecutor.java:273) =09at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecu= tor.java:125) =09at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.= java:272) =09at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java= :185) =09at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) =09at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java= :110) =09at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttp= Client.java:185) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:83) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:56) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:548) =09... 50 more FAILED: org.apache.solr.update.PeerSyncWithLeaderTest.test Error Message: Unexpected exception type, expected SolrException but got org.apache.solr.c= lient.solrj.SolrServerException: Timeout occurred while waiting response fr= om server at: http://127.0.0.1:38520/bg/collection1 Stack Trace: junit.framework.AssertionFailedError: Unexpected exception type, expected S= olrException but got org.apache.solr.client.solrj.SolrServerException: Time= out occurred while waiting response from server at: http://127.0.0.1:38520/= bg/collection1 =09at __randomizedtesting.SeedInfo.seed([3305A115FE94BB4A:BB519ECF5068D6B2]= :0) =09at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.jav= a:2700) =09at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.jav= a:2689) =09at org.apache.solr.update.PeerSyncTest.test(PeerSyncTest.java:279) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java: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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:1054) =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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occurr= ed while waiting response from server at: http://127.0.0.1:38520/bg/collect= ion1 =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:660) =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.SolrRequest.process(SolrRequest.java:207= ) =09at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224= ) =09at org.apache.solr.BaseDistributedSearchTestCase.add(BaseDistributedSear= chTestCase.java:576) =09at org.apache.solr.update.PeerSyncTest.lambda$test$0(PeerSyncTest.java:2= 81) =09at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.jav= a:2695) =09... 43 more Caused by: java.net.SocketTimeoutException: Read timed out =09at java.net.SocketInputStream.socketRead0(Native Method) =09at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) =09at java.net.SocketInputStream.read(SocketInputStream.java:171) =09at java.net.SocketInputStream.read(SocketInputStream.java:141) =09at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInpu= tBufferImpl.java:137) =09at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInpu= tBufferImpl.java:153) =09at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputB= ufferImpl.java:282) =09at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(Default= HttpResponseParser.java:138) =09at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(Default= HttpResponseParser.java:56) =09at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessagePa= rser.java:259) =09at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHead= er(DefaultBHttpClientConnection.java:163) =09at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy= .java:165) =09at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRe= questExecutor.java:273) =09at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecu= tor.java:125) =09at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.= java:272) =09at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java= :185) =09at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) =09at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java= :110) =09at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttp= Client.java:185) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:83) =09at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttp= Client.java:56) =09at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSo= lrClient.java:548) =09... 50 more FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test Error Message: Thread pool didn't terminate within 15 secs Stack Trace: java.lang.AssertionError: Thread pool didn't terminate within 15 secs =09at __randomizedtesting.SeedInfo.seed([3305A115FE94BB4A:BB519ECF5068D6B2]= :0) =09at org.junit.Assert.fail(Assert.java:88) =09at org.junit.Assert.assertTrue(Assert.java:41) =09at org.apache.solr.update.TestInPlaceUpdatesDistrib.reorderedDeletesTest= (TestInPlaceUpdatesDistrib.java:776) =09at org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpda= tesDistrib.java:175) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java: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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:1054) =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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:368) =09at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 13556 lines...] [junit4] Suite: org.apache.solr.update.PeerSyncTest [junit4] 2> 559024 INFO (SUITE-PeerSyncTest-seed#[3305A115FE94BB4A]-w= orker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.al= lowed.securerandom=3Dnull & java.security.egd=3Dfile:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Luce= ne-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.update.P= eerSyncTest_3305A115FE94BB4A-001/init-core-data-001 [junit4] 2> 559025 WARN (SUITE-PeerSyncTest-seed#[3305A115FE94BB4A]-w= orker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3D8 num= Closes=3D8 [junit4] 2> 559025 INFO (SUITE-PeerSyncTest-seed#[3305A115FE94BB4A]-w= orker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPRO= P=3Dtrue) w/NUMERIC_DOCVALUES_SYSPROP=3Dfalse [junit4] 2> 559028 INFO (SUITE-PeerSyncTest-seed#[3305A115FE94BB4A]-w= orker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (f= alse) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=3Dhttps://iss= ues.apache.org/jira/browse/SOLR-5776) [junit4] 2> 559028 INFO (SUITE-PeerSyncTest-seed#[3305A115FE94BB4A]-w= orker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext syste= m property: /nk_z/a [junit4] 2> 559030 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 559031 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, f= rom paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests= -8.x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-sla= ve/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/= collection1/lib/classes] [junit4] 2> 559060 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 559079 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.IndexSchema [null] Schema name=3Dtest [junit4] 2> 559193 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field i= d [junit4] 2> 559607 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: Wh= itelistHostChecker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Df= alse] [junit4] 2> 559613 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@6388fa4[provider=3Dnull,keyStore=3Dnull,trust= Store=3Dnull] [junit4] 2> 559617 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@3c9d332c[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 559642 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache= for 2147483647 transient cores [junit4] 2> 559642 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping m= etrics history in memory. [junit4] 2> 559664 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (regist= ry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5= d168182 [junit4] 2> 559675 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registr= y 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1= 68182 [junit4] 2> 559675 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (regis= try 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer= @5d168182 [junit4] 2> 559678 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.= x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/= workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/col= lection1/lib/classes] [junit4] 2> 559713 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 559740 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 559857 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 559867 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Bad= Apples-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 559867 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1= ' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanse= rver.JmxMBeanServer@5d168182 [junit4] 2> 559868 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/t= est-files/solr/collection1], dataDir=3D[/x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.upd= ate.PeerSyncTest_3305A115FE94BB4A-001/init-core-data-001/] [junit4] 2> 559870 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize= =3D0, mergeFactor=3D10, maxMergeSize=3D749581179, maxMergeSizeForForcedMerg= e=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.1] [junit4] 2> 559872 WARN (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler,attributes = =3D {initParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {de= faults=3D{a=3DA,b=3DB}}} [junit4] 2> 559926 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 559926 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 559928 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 559928 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 559929 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D48,= maxMergeAtOnceExplicit=3D15, maxMergedSegmentMB=3D96.130859375, floorSegme= ntMB=3D1.5888671875, forceMergeDeletesPctAllowed=3D23.96773388076008, segme= ntsPerTier=3D42.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D1.0, deletesPctAllowed=3D20.952375949953108 [junit4] 2> 559929 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@20ce0ae2[collection1] m= ain] [junit4] 2> 559929 WARN (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1= /jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/= src/test-files/solr/collection1/conf; switching to use InMemory storage ins= tead. [junit4] 2> 559930 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 559930 INFO (coreLoadExecutor-2177-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1632311583198674944 [junit4] 2> 559933 INFO (searcherExecutor-2178-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@20ce0ae2[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 559933 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 560117 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins= /jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-co= re/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/c= ontrol/cores/collection1 [junit4] 2> 560118 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linge= r time [junit4] 2> 560119 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured por= t=3D0) [junit4] 2> 560119 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try = number 1 ... [junit4] 2> 560119 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:3= 1.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 560120 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 560120 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 560120 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 560122 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4= 9ac5468{/nk_z/a,null,AVAILABLE} [junit4] 2> 560123 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@270aaebf{HTTP/= 1.1,[http/1.1, h2c]}{127.0.0.1:46250} [junit4] 2> 560123 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server Started @560182ms [junit4] 2> 560123 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=3Dschema.x= ml, solrconfig=3Dsolrconfig-tlog.xml, hostContext=3D/nk_z/a, hostPort=3D462= 50, coreRootDirectory=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Bad= Apples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.update.PeerSyncTest= _3305A115FE94BB4A-001/tempDir-001/control/cores} [junit4] 2> 560123 ERROR (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Lo= gging may be missing or incomplete. [junit4] 2> 560124 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.loggi= ng.slf4j.Log4jLoggerFactory [junit4] 2> 560124 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache S= olr=E2=84=A2 version 8.1.0 [junit4] 2> 560124 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standal= one mode on port null [junit4] 2> 560124 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 560124 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05= -01T06:53:53.463Z [junit4] 2> 560124 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/j= enkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/s= olr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir= -001/control/solr.xml [junit4] 2> 560129 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rWorkLoopDelay is ignored [junit4] 2> 560129 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rBadNodeExpiration is ignored [junit4] 2> 560131 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserve= r.JmxMBeanServer@5d168182, but no JMX reporters were configured - adding de= fault JMX reporter. [junit4] 2> 560175 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: Wh= itelistHostChecker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Df= alse] [junit4] 2> 560177 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@72deaeab[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 560181 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@7ebd98c0[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 560207 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache= for 4 transient cores [junit4] 2> 560207 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping m= etrics history in memory. [junit4] 2> 560237 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (regist= ry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5= d168182 [junit4] 2> 560253 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registr= y 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1= 68182 [junit4] 2> 560253 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (regis= try 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer= @5d168182 [junit4] 2> 560256 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneat= h /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/= build/solr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/= tempDir-001/control/cores [junit4] 2> 560256 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 560257 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.= x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/= workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/col= lection1/lib/classes] [junit4] 2> 560291 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 560312 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 560439 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 560449 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadAp= ples-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 560450 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1= ' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanse= rver.JmxMBeanServer@5d168182 [junit4] 2> 560450 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/t= est-files/solr/collection1], dataDir=3D[/x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.upd= ate.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/control/cores/collection1= /data/] [junit4] 2> 560453 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize= =3D0, mergeFactor=3D10, maxMergeSize=3D749581179, maxMergeSizeForForcedMerg= e=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.1] [junit4] 2> 560456 WARN (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler,attributes = =3D {initParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {de= faults=3D{a=3DA,b=3DB}}} [junit4] 2> 560516 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 560516 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 560517 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 560517 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 560519 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D48,= maxMergeAtOnceExplicit=3D15, maxMergedSegmentMB=3D96.130859375, floorSegme= ntMB=3D1.5888671875, forceMergeDeletesPctAllowed=3D23.96773388076008, segme= ntsPerTier=3D42.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D1.0, deletesPctAllowed=3D20.952375949953108 [junit4] 2> 560519 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@267b862d[collection1] m= ain] [junit4] 2> 560519 WARN (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1= /jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/= src/test-files/solr/collection1/conf; switching to use InMemory storage ins= tead. [junit4] 2> 560520 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 560520 INFO (coreLoadExecutor-2191-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1632311583817334784 [junit4] 2> 560523 INFO (searcherExecutor-2192-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@267b862d[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 560699 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins= /jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-co= re/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/s= hard0/cores/collection1 [junit4] 2> 560700 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linge= r time [junit4] 2> 560700 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured por= t=3D0) [junit4] 2> 560700 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try = number 1 ... [junit4] 2> 560700 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:3= 1.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 560702 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 560702 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 560702 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 560702 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6= b58639e{/nk_z/a,null,AVAILABLE} [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6fbb9226{HTTP/= 1.1,[http/1.1, h2c]}{127.0.0.1:46572} [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server Started @560761ms [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=3Dschema.x= ml, solrconfig=3Dsolrconfig-tlog.xml, hostContext=3D/nk_z/a, hostPort=3D465= 72, coreRootDirectory=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Bad= Apples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.update.PeerSyncTest= _3305A115FE94BB4A-001/tempDir-001/shard0/cores} [junit4] 2> 560703 ERROR (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Lo= gging may be missing or incomplete. [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.loggi= ng.slf4j.Log4jLoggerFactory [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache S= olr=E2=84=A2 version 8.1.0 [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standal= one mode on port null [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 560703 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05= -01T06:53:54.042Z [junit4] 2> 560704 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/j= enkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/s= olr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir= -001/shard0/solr.xml [junit4] 2> 560708 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rWorkLoopDelay is ignored [junit4] 2> 560708 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rBadNodeExpiration is ignored [junit4] 2> 560710 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserve= r.JmxMBeanServer@5d168182, but no JMX reporters were configured - adding de= fault JMX reporter. [junit4] 2> 560874 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: Wh= itelistHostChecker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Df= alse] [junit4] 2> 560876 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@44e596f8[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 560880 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@579d41ab[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 560905 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache= for 4 transient cores [junit4] 2> 560905 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping m= etrics history in memory. [junit4] 2> 560932 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (regist= ry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5= d168182 [junit4] 2> 560949 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registr= y 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1= 68182 [junit4] 2> 560949 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (regis= try 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer= @5d168182 [junit4] 2> 560951 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneat= h /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/= build/solr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/= tempDir-001/shard0/cores [junit4] 2> 560951 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 560953 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.= x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/= workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/col= lection1/lib/classes] [junit4] 2> 560992 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 561013 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 561137 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 561147 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadAp= ples-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 561147 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1= ' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanse= rver.JmxMBeanServer@5d168182 [junit4] 2> 561148 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/t= est-files/solr/collection1], dataDir=3D[/x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.upd= ate.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/shard0/cores/collection1/= data/] [junit4] 2> 561150 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize= =3D0, mergeFactor=3D10, maxMergeSize=3D749581179, maxMergeSizeForForcedMerg= e=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.1] [junit4] 2> 561152 WARN (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler,attributes = =3D {initParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {de= faults=3D{a=3DA,b=3DB}}} [junit4] 2> 561218 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 561218 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 561220 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 561220 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 561221 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D48,= maxMergeAtOnceExplicit=3D15, maxMergedSegmentMB=3D96.130859375, floorSegme= ntMB=3D1.5888671875, forceMergeDeletesPctAllowed=3D23.96773388076008, segme= ntsPerTier=3D42.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D1.0, deletesPctAllowed=3D20.952375949953108 [junit4] 2> 561222 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@24381257[collection1] m= ain] [junit4] 2> 561222 WARN (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1= /jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/= src/test-files/solr/collection1/conf; switching to use InMemory storage ins= tead. [junit4] 2> 561223 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 561223 INFO (coreLoadExecutor-2205-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1632311584554483712 [junit4] 2> 561226 INFO (searcherExecutor-2206-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@24381257[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 561404 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins= /jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-co= re/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/s= hard1/cores/collection1 [junit4] 2> 561405 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linge= r time [junit4] 2> 561406 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured por= t=3D0) [junit4] 2> 561406 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try = number 1 ... [junit4] 2> 561406 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:3= 1.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 561407 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 561407 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 561407 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 561408 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2= e436bbb{/nk_z/a,null,AVAILABLE} [junit4] 2> 561408 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@12e1a364{HTTP/= 1.1,[http/1.1, h2c]}{127.0.0.1:45728} [junit4] 2> 561408 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server Started @561467ms [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=3Dschema.x= ml, solrconfig=3Dsolrconfig-tlog.xml, hostContext=3D/nk_z/a, hostPort=3D457= 28, coreRootDirectory=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Bad= Apples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.update.PeerSyncTest= _3305A115FE94BB4A-001/tempDir-001/shard1/cores} [junit4] 2> 561409 ERROR (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Lo= gging may be missing or incomplete. [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.loggi= ng.slf4j.Log4jLoggerFactory [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache S= olr=E2=84=A2 version 8.1.0 [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standal= one mode on port null [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05= -01T06:53:54.748Z [junit4] 2> 561409 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/j= enkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/s= olr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir= -001/shard1/solr.xml [junit4] 2> 561414 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rWorkLoopDelay is ignored [junit4] 2> 561414 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rBadNodeExpiration is ignored [junit4] 2> 561416 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserve= r.JmxMBeanServer@5d168182, but no JMX reporters were configured - adding de= fault JMX reporter. [junit4] 2> 561467 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: Wh= itelistHostChecker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Df= alse] [junit4] 2> 561469 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@623a909a[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 561474 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@26aa7f22[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 561501 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache= for 4 transient cores [junit4] 2> 561501 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping m= etrics history in memory. [junit4] 2> 561529 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (regist= ry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5= d168182 [junit4] 2> 561546 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registr= y 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1= 68182 [junit4] 2> 561546 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (regis= try 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer= @5d168182 [junit4] 2> 561548 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneat= h /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/= build/solr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/= tempDir-001/shard1/cores [junit4] 2> 561549 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 561549 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.= x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/= workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/col= lection1/lib/classes] [junit4] 2> 561605 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 561625 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 561748 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 561758 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadAp= ples-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 561758 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1= ' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanse= rver.JmxMBeanServer@5d168182 [junit4] 2> 561758 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/t= est-files/solr/collection1], dataDir=3D[/x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.upd= ate.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/shard1/cores/collection1/= data/] [junit4] 2> 561761 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize= =3D0, mergeFactor=3D10, maxMergeSize=3D749581179, maxMergeSizeForForcedMerg= e=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.1] [junit4] 2> 561763 WARN (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler,attributes = =3D {initParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {de= faults=3D{a=3DA,b=3DB}}} [junit4] 2> 561822 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 561822 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 561823 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 561823 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 561824 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D48,= maxMergeAtOnceExplicit=3D15, maxMergedSegmentMB=3D96.130859375, floorSegme= ntMB=3D1.5888671875, forceMergeDeletesPctAllowed=3D23.96773388076008, segme= ntsPerTier=3D42.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D1.0, deletesPctAllowed=3D20.952375949953108 [junit4] 2> 561825 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f26032[collection1] ma= in] [junit4] 2> 561825 WARN (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1= /jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/= src/test-files/solr/collection1/conf; switching to use InMemory storage ins= tead. [junit4] 2> 561826 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 561826 INFO (coreLoadExecutor-2219-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1632311585186775040 [junit4] 2> 561834 INFO (searcherExecutor-2220-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@4f26032[collection1] main{ExitableDirectoryReader(Uni= nvertingDirectoryReader())} [junit4] 2> 562028 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins= /jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-co= re/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/s= hard2/cores/collection1 [junit4] 2> 562029 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linge= r time [junit4] 2> 562029 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured por= t=3D0) [junit4] 2> 562029 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try = number 1 ... [junit4] 2> 562029 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:3= 1.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 562031 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=3Dnode0 [junit4] 2> 562031 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 562031 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 562032 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4= c61a1d4{/nk_z/a,null,AVAILABLE} [junit4] 2> 562032 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69745556{HTTP/= 1.1,[http/1.1, h2c]}{127.0.0.1:37401} [junit4] 2> 562032 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.s.Server Started @562091ms [junit4] 2> 562032 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=3Dschema.x= ml, solrconfig=3Dsolrconfig-tlog.xml, hostContext=3D/nk_z/a, hostPort=3D374= 01, coreRootDirectory=3D/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Bad= Apples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.update.PeerSyncTest= _3305A115FE94BB4A-001/tempDir-001/shard2/cores} [junit4] 2> 562033 ERROR (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Lo= gging may be missing or incomplete. [junit4] 2> 562033 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.loggi= ng.slf4j.Log4jLoggerFactory [junit4] 2> 562033 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache S= olr=E2=84=A2 version 8.1.0 [junit4] 2> 562033 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standal= one mode on port null [junit4] 2> 562033 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 562033 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05= -01T06:53:55.372Z [junit4] 2> 562033 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/j= enkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/s= olr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/tempDir= -001/shard2/solr.xml [junit4] 2> 562038 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rWorkLoopDelay is ignored [junit4] 2> 562038 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailove= rBadNodeExpiration is ignored [junit4] 2> 562040 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserve= r.JmxMBeanServer@5d168182, but no JMX reporters were configured - adding de= fault JMX reporter. [junit4] 2> 562173 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: Wh= itelistHostChecker [whitelistHosts=3Dnull, whitelistHostCheckingEnabled=3Df= alse] [junit4] 2> 562175 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@5ef95c6c[provider=3Dnull,keyStore=3Dnull,trus= tStore=3Dnull] [junit4] 2> 562180 WARN (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm con= figured for SslContextFactory@d612441[provider=3Dnull,keyStore=3Dnull,trust= Store=3Dnull] [junit4] 2> 562206 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache= for 4 transient cores [junit4] 2> 562206 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping m= etrics history in memory. [junit4] 2> 562234 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (regist= ry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5= d168182 [junit4] 2> 562250 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registr= y 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d1= 68182 [junit4] 2> 562250 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (regis= try 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer= @5d168182 [junit4] 2> 562253 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneat= h /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/= build/solr-core/test/J1/temp/solr.update.PeerSyncTest_3305A115FE94BB4A-001/= tempDir-001/shard2/cores [junit4] 2> 562253 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 562254 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.= x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/= workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/test-files/solr/col= lection1/lib/classes] [junit4] 2> 562289 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.1.0 [junit4] 2> 562322 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 562455 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 562475 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadAp= ples-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=3Dtrue [junit4] 2> 562476 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1= ' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanse= rver.JmxMBeanServer@5d168182 [junit4] 2> 562476 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenk= ins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/src/t= est-files/solr/collection1], dataDir=3D[/x1/jenkins/jenkins-slave/workspace= /Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.upd= ate.PeerSyncTest_3305A115FE94BB4A-001/tempDir-001/shard2/cores/collection1/= data/] [junit4] 2> 562478 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize= =3D0, mergeFactor=3D10, maxMergeSize=3D749581179, maxMergeSizeForForcedMerg= e=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147= 483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.1] [junit4] 2> 562480 WARN (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = =3D requestHandler,name =3D /dump,class =3D DumpRequestHandler,attributes = =3D {initParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {de= faults=3D{a=3DA,b=3DB}}} [junit4] 2> 562540 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 562540 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 562542 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 562542 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 562543 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D48,= maxMergeAtOnceExplicit=3D15, maxMergedSegmentMB=3D96.130859375, floorSegme= ntMB=3D1.5888671875, forceMergeDeletesPctAllowed=3D23.96773388076008, segme= ntsPerTier=3D42.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D1.0, deletesPctAllowed=3D20.952375949953108 [junit4] 2> 562544 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b6ef780[collection1] m= ain] [junit4] 2> 562544 WARN (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1= /jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/core/= src/test-files/solr/collection1/conf; switching to use InMemory storage ins= tead. [junit4] 2> 562545 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 562545 INFO (coreLoadExecutor-2233-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1632311585940701184 [junit4] 2> 562548 INFO (searcherExecutor-2234-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@4b6ef780[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader())} [junit4] 2> 562548 INFO (TEST-PeerSyncTest.test-seed#[3305A115FE94BB4= A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 562554 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{update.distrib=3DFROMLEADER&wt=3Djavabin&version=3D2}{add=3D[= 1 (1)]} 0 2 [junit4] 2> 562556 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D START replicas=3D[http://1= 27.0.0.1:46572/nk_z/a/collection1] nUpdates=3D100 [junit4] 2> 562563 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@7c5589ab[collection1] realtime] [junit4] 2> 562563 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:5.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D1, maxInHash=3D1, versions= Hash=3D-5451962507482445012, numVersions=3D1, numDocs=3D1, maxDoc=3D1} [junit4] 2> 562563 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{distrib= =3Dfalse&qt=3D/get&getFingerprint=3D9223372036854775807&wt=3Djavabin&versio= n=3D2} status=3D0 QTime=3D5 [junit4] 2> 562566 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:1.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D0, maxInHash=3D0, versions= Hash=3D0, numVersions=3D0, numDocs=3D0, maxDoc=3D0} [junit4] 2> 562566 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D DONE. We have no versions= . sync failed. [junit4] 2> 562567 INFO (qtp1937432173-6318) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:0.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D1, maxInHash=3D1, versions= Hash=3D-5451962507482445012, numVersions=3D1, numDocs=3D1, maxDoc=3D1} [junit4] 2> 562567 INFO (qtp1937432173-6318) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{distrib= =3Dfalse&qt=3D/get&fingerprint=3Dtrue&getVersions=3D100&wt=3Djavabin&versio= n=3D2} status=3D0 QTime=3D0 [junit4] 2> 562567 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{qt=3D/g= et&getVersions=3D100&sync=3Dhttp://127.0.0.1:46572/nk_z/a/collection1&wt=3D= javabin&version=3D2} status=3D0 QTime=3D11 [junit4] 2> 562570 INFO (qtp1727718363-6337) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{update.distrib=3DFROMLEADER&wt=3Djavabin&version=3D2}{add=3D[= 1 (1)]} 0 1 [junit4] 2> 562571 INFO (qtp1727718363-6338) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D START replicas=3D[http://1= 27.0.0.1:46572/nk_z/a/collection1] nUpdates=3D100 [junit4] 2> 562571 INFO (qtp1937432173-6314) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:0.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D1, maxInHash=3D1, versions= Hash=3D-5451962507482445012, numVersions=3D1, numDocs=3D1, maxDoc=3D1} [junit4] 2> 562571 INFO (qtp1937432173-6314) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{distrib= =3Dfalse&qt=3D/get&getFingerprint=3D9223372036854775807&wt=3Djavabin&versio= n=3D2} status=3D0 QTime=3D0 [junit4] 2> 562576 INFO (qtp1727718363-6338) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@71ba76e3[collection1] realtime] [junit4] 2> 562576 INFO (qtp1727718363-6338) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:4.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D1, maxInHash=3D1, versions= Hash=3D-5451962507482445012, numVersions=3D1, numDocs=3D1, maxDoc=3D1} [junit4] 2> 562577 INFO (qtp1727718363-6338) [ x:collection1] o.a.= s.u.PeerSync We are already in sync. No need to do a PeerSync=20 [junit4] 2> 562577 INFO (qtp1727718363-6338) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{qt=3D/g= et&getVersions=3D100&sync=3Dhttp://127.0.0.1:46572/nk_z/a/collection1&wt=3D= javabin&version=3D2} status=3D0 QTime=3D6 [junit4] 2> 562577 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 start commit{_version_=3D1632311585974255616,optim= ize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,= softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 562578 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.So= lrIndexWriter@284f6e9f commitCommandVersion:1632311585974255616 [junit4] 2> 562580 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@2fbdc759[collection1] main] [junit4] 2> 562581 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 562583 INFO (searcherExecutor-2206-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@2fbdc759[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics=3D{os=3DLinu= x, java.vendor=3DOracle Corporation, java.version=3D1.8.0_191, java.vm.vers= ion=3D25.191-b12, lucene.version=3D8.1.0, os.arch=3Damd64, java.runtime.ver= sion=3D1.8.0_191-b12, source=3Dflush, os.version=3D4.4.0-137-generic, times= tamp=3D1556693635900}]:[attributes=3D{Lucene50StoredFieldsFormat.mode=3DBES= T_SPEED}])))} [junit4] 2> 562583 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{waitSearcher=3Dtrue&commit=3Dtrue&softCommit=3Dfalse&wt=3Djav= abin&version=3D2}{commit=3D} 0 6 [junit4] 2> 562584 INFO (qtp1727718363-6339) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 start commit{_version_=3D1632311585981595648,optim= ize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,= softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 562584 INFO (qtp1727718363-6339) [ x:collection1] o.a.= s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.So= lrIndexWriter@437ad4a6 commitCommandVersion:1632311585981595648 [junit4] 2> 562586 INFO (qtp1727718363-6339) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@2a6e4884[collection1] main] [junit4] 2> 562586 INFO (qtp1727718363-6339) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 562590 INFO (searcherExecutor-2220-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@2a6e4884[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics=3D{os=3DLinu= x, java.vendor=3DOracle Corporation, java.version=3D1.8.0_191, java.vm.vers= ion=3D25.191-b12, lucene.version=3D8.1.0, os.arch=3Damd64, java.runtime.ver= sion=3D1.8.0_191-b12, source=3Dflush, os.version=3D4.4.0-137-generic, times= tamp=3D1556693635913}]:[attributes=3D{Lucene50StoredFieldsFormat.mode=3DBES= T_SPEED}])))} [junit4] 2> 562590 INFO (qtp1727718363-6339) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{waitSearcher=3Dtrue&commit=3Dtrue&softCommit=3Dfalse&wt=3Djav= abin&version=3D2}{commit=3D} 0 6 [junit4] 2> 562591 INFO (qtp1937432173-6316) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/select params=3D{q=3D= *:*&wt=3Djavabin&version=3D2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 562592 INFO (qtp1727718363-6335) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/select params=3D{q=3D= *:*&wt=3Djavabin&version=3D2} hits=3D1 status=3D0 QTime=3D0 [junit4] 2> 562598 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{update.distrib=3DFROMLEADER&wt=3Djavabin&version=3D2}{add=3D[= 2 (2)]} 0 2 [junit4] 2> 562599 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D START replicas=3D[http://1= 27.0.0.1:46572/nk_z/a/collection1] nUpdates=3D100 [junit4] 2> 562604 INFO (qtp1937432173-6318) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@29715c85[collection1] realtime] [junit4] 2> 562605 INFO (qtp1937432173-6318) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:5.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D2, maxInHash=3D2, versions= Hash=3D-1218814014108643565, numVersions=3D2, numDocs=3D2, maxDoc=3D1} [junit4] 2> 562605 INFO (qtp1937432173-6318) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{distrib= =3Dfalse&qt=3D/get&getFingerprint=3D9223372036854775807&wt=3Djavabin&versio= n=3D2} status=3D0 QTime=3D5 [junit4] 2> 562605 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:0.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D1, maxInHash=3D1, versions= Hash=3D-5451962507482445012, numVersions=3D1, numDocs=3D1, maxDoc=3D1} [junit4] 2> 562607 INFO (qtp1937432173-6314) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:0.0 result:{maxVersionSpecifie= d=3D9223372036854775807, maxVersionEncountered=3D2, maxInHash=3D2, versions= Hash=3D-1218814014108643565, numVersions=3D2, numDocs=3D2, maxDoc=3D1} [junit4] 2> 562607 INFO (qtp1937432173-6314) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{distrib= =3Dfalse&qt=3D/get&fingerprint=3Dtrue&getVersions=3D100&wt=3Djavabin&versio= n=3D2} status=3D0 QTime=3D0 [junit4] 2> 562607 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D Received 2 versions from = http://127.0.0.1:46572/nk_z/a/collection1 fingerprint:{maxVersionSpecified= =3D9223372036854775807, maxVersionEncountered=3D2, maxInHash=3D2, versionsH= ash=3D-1218814014108643565, numVersions=3D2, numDocs=3D2, maxDoc=3D1} [junit4] 2> 562608 INFO (qtp1937432173-6315) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{distrib= =3Dfalse&qt=3D/get&checkCanHandleVersionRanges=3Dfalse&wt=3Djavabin&version= =3D2} status=3D0 QTime=3D0 [junit4] 2> 562609 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D Requesting updates from ht= tp://127.0.0.1:46572/nk_z/a/collection1n=3D1 versions=3D2...2 [junit4] 2> 562612 INFO (qtp1937432173-6316) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:0.0 result:{maxVersionSpecifie= d=3D2, maxVersionEncountered=3D2, maxInHash=3D2, versionsHash=3D-1218814014= 108643565, numVersions=3D2, numDocs=3D2, maxDoc=3D1} [junit4] 2> 562613 INFO (qtp1937432173-6316) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{getUpda= tes=3D2...2&distrib=3Dfalse&qt=3D/get&fingerprint=3Dtrue&onlyIfActive=3Dfal= se&wt=3Djavabin&version=3D2} status=3D0 QTime=3D1 [junit4] 2> 562617 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] {add=3D[2 (2)]} 0 3 [junit4] 2> 562622 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@20fee405[collection1] realtime] [junit4] 2> 562622 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.IndexFingerprint IndexFingerprint millis:5.0 result:{maxVersionSpecifie= d=3D2, maxVersionEncountered=3D2, maxInHash=3D2, versionsHash=3D-1218814014= 108643565, numVersions=3D2, numDocs=3D2, maxDoc=3D1} [junit4] 2> 562622 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync Fingerprint comparison: 0 [junit4] 2> 562623 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.u.PeerSync PeerSync: core=3Dcollection1 url=3D DONE. sync succeeded [junit4] 2> 562623 INFO (qtp1727718363-6336) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/get params=3D{qt=3D/g= et&getVersions=3D100&sync=3Dhttp://127.0.0.1:46572/nk_z/a/collection1&wt=3D= javabin&version=3D2} status=3D0 QTime=3D24 [junit4] 2> 562624 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 start commit{_version_=3D1632311586023538688,optim= ize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,= softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 562624 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.So= lrIndexWriter@284f6e9f commitCommandVersion:1632311586023538688 [junit4] 2> 562625 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@149e343e[collection1] main] [junit4] 2> 562626 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 562628 INFO (searcherExecutor-2206-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@149e343e[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics=3D{os=3DLinu= x, java.vendor=3DOracle Corporation, java.version=3D1.8.0_191, java.vm.vers= ion=3D25.191-b12, lucene.version=3D8.1.0, os.arch=3Damd64, java.runtime.ver= sion=3D1.8.0_191-b12, source=3Dflush, os.version=3D4.4.0-137-generic, times= tamp=3D1556693635900}]:[attributes=3D{Lucene50StoredFieldsFormat.mode=3DBES= T_SPEED}]) Uninverting(_1(8.1.0):C1:[diagnostics=3D{os=3DLinux, java.vendor= =3DOracle Corporation, java.version=3D1.8.0_191, java.vm.version=3D25.191-b= 12, lucene.version=3D8.1.0, os.arch=3Damd64, java.runtime.version=3D1.8.0_1= 91-b12, source=3Dflush, os.version=3D4.4.0-137-generic, timestamp=3D1556693= 635941}]:[attributes=3D{Lucene50StoredFieldsFormat.mode=3DBEST_SPEED}])))} [junit4] 2> 562629 INFO (qtp1937432173-6317) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{waitSearcher=3Dtrue&commit=3Dtrue&softCommit=3Dfalse&wt=3Djav= abin&version=3D2}{commit=3D} 0 5 [junit4] 2> 562630 INFO (qtp1727718363-6337) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 start commit{_version_=3D1632311586029830144,optim= ize=3Dfalse,openSearcher=3Dtrue,waitSearcher=3Dtrue,expungeDeletes=3Dfalse,= softCommit=3Dfalse,prepareCommit=3Dfalse} [junit4] 2> 562630 INFO (qtp1727718363-6337) [ x:collection1] o.a.= s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.So= lrIndexWriter@437ad4a6 commitCommandVersion:1632311586029830144 [junit4] 2> 562631 INFO (qtp1727718363-6337) [ x:collection1] o.a.= s.s.SolrIndexSearcher Opening [Searcher@546c9da1[collection1] main] [junit4] 2> 562636 INFO (searcherExecutor-2220-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@546c9da1[collection1] main{ExitableDirectoryReader(Un= invertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics=3D{os=3DLinu= x, java.vendor=3DOracle Corporation, java.version=3D1.8.0_191, java.vm.vers= ion=3D25.191-b12, lucene.version=3D8.1.0, os.arch=3Damd64, java.runtime.ver= sion=3D1.8.0_191-b12, source=3Dflush, os.version=3D4.4.0-137-generic, times= tamp=3D1556693635913}]:[attributes=3D{Lucene50StoredFieldsFormat.mode=3DBES= T_SPEED}]) Uninverting(_1(8.1.0):C1:[diagnostics=3D{os=3DLinux, java.vendor= =3DOracle Corporation, java.version=3D1.8.0_191, java.vm.version=3D25.191-b= 12, lucene.version=3D8.1.0, os.arch=3Damd64, java.runtime.version=3D1.8.0_1= 91-b12, source=3Dflush, os.version=3D4.4.0-137-generic, timestamp=3D1556693= 635959}]:[attributes=3D{Lucene50StoredFieldsFormat.mode=3DBEST_SPEED}])))} [junit4] 2> 562637 INFO (qtp1727718363-6337) [ x:collection1] o.a.= s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 562637 INFO (qtp1727718363-6337) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{waitSearcher=3Dtrue&commit=3Dtrue&softCommit=3Dfalse&wt=3Djav= abin&version=3D2}{commit=3D} 0 7 [junit4] 2> 562638 INFO (qtp1937432173-6318) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/select params=3D{q=3D= *:*&wt=3Djavabin&version=3D2} hits=3D2 status=3D0 QTime=3D0 [junit4] 2> 562640 INFO (qtp1727718363-6338) [ x:collection1] o.a.= s.c.S.Request [collection1] webapp=3D/nk_z/a path=3D/select params=3D{q=3D= *:*&wt=3Djavabin&version=3D2} hits=3D2 status=3D0 QTime=3D0 [junit4] 2> 562645 INFO (qtp1937432173-6314) [ x:collection1] o.a.= s.u.p.LogUpdateProcessorFactory [collection1] webapp=3D/nk_z/a path=3D/upd= ate params=3D{update.distri [...truncated too long message...] ainer Shutting down CoreContainer instance=3D1221543727 [junit4] 2> 2676428 INFO (coreCloseExecutor-12866-thread-1) [ x:co= llection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr= .core.SolrCore@5280b15b [junit4] 2> 2676428 INFO (coreCloseExecutor-12866-thread-1) [ x:co= llection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry= =3Dsolr.core.collection1, tag=3D5280b15b [junit4] 2> 2676429 INFO (coreCloseExecutor-12866-thread-1) [ x:co= llection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metr= ics.reporters.SolrJmxReporter@5c93c68f: rootName =3D null, domain =3D solr.= core.collection1, service url =3D null, agent id =3D null] for registry sol= r.core.collection1 / com.codahale.metrics.MetricRegistry@551f87b5 [junit4] 2> 2676433 INFO (SUITE-PeerSyncWithLeaderTest-seed#[3305A115= FE94BB4A]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters= for registry=3Dsolr.node, tag=3Dnull [junit4] 2> 2676433 INFO (SUITE-PeerSyncWithLeaderTest-seed#[3305A115= FE94BB4A]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.ap= ache.solr.metrics.reporters.SolrJmxReporter@4ae69d2d: rootName =3D null, do= main =3D solr.node, service url =3D null, agent id =3D null] for registry s= olr.node / com.codahale.metrics.MetricRegistry@779fe7f [junit4] 2> 2676434 INFO (SUITE-PeerSyncWithLeaderTest-seed#[3305A115= FE94BB4A]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters= for registry=3Dsolr.jvm, tag=3Dnull [junit4] 2> 2676434 INFO (SUITE-PeerSyncWithLeaderTest-seed#[3305A115= FE94BB4A]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.ap= ache.solr.metrics.reporters.SolrJmxReporter@506d267b: rootName =3D null, do= main =3D solr.jvm, service url =3D null, agent id =3D null] for registry so= lr.jvm / com.codahale.metrics.MetricRegistry@337a6f38 [junit4] 2> 2676434 INFO (SUITE-PeerSyncWithLeaderTest-seed#[3305A115= FE94BB4A]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters= for registry=3Dsolr.jetty, tag=3Dnull [junit4] 2> 2676434 INFO (SUITE-PeerSyncWithLeaderTest-seed#[3305A115= FE94BB4A]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.ap= ache.solr.metrics.reporters.SolrJmxReporter@1e0ec3ce: rootName =3D null, do= main =3D solr.jetty, service url =3D null, agent id =3D null] for registry = solr.jetty / com.codahale.metrics.MetricRegistry@62558fc2 [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenk= ins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/te= st/J1/temp/solr.update.PeerSyncWithLeaderTest_3305A115FE94BB4A-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene80): {title= _stringNoNorms=3DBlockTreeOrds(blocksize=3D128), title_stemmed=3DFSTOrd50, = multiDefault=3DFSTOrd50, _root_=3DBlockTreeOrds(blocksize=3D128), id=3DLuce= ne50(blocksize=3D128), text=3DPostingsFormat(name=3DLuceneFixedGap), title= =3DFSTOrd50, title_lettertok=3DLucene50(blocksize=3D128)}, docValues:{range= _facet_l_dv=3DDocValuesFormat(name=3DLucene80), _version_=3DDocValuesFormat= (name=3DDirect), val_i_dvo=3DDocValuesFormat(name=3DLucene80), range_facet_= i_dv=3DDocValuesFormat(name=3DDirect), intDvoDefault=3DDocValuesFormat(name= =3DLucene80), timestamp=3DDocValuesFormat(name=3DDirect)}, maxPointsInLeafN= ode=3D920, maxMBSortInHeap=3D5.026849421629477, sim=3DAsserting(org.apache.= lucene.search.similarities.AssertingSimilarity@61a3ef2f), locale=3Dfr-BE, t= imezone=3DAsia/Dubai [junit4] 2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 1.8= .0_191 (64-bit)/cpus=3D4,threads=3D1,free=3D142455664,total=3D528482304 [junit4] 2> NOTE: All tests run in this JVM: [RulesTest, TestSchemaVer= sionResource, SchemaApiFailureTest, TestDynamicURP, TestTolerantUpdateProce= ssorCloud, TestDeleteCollectionOnDownNodes, SoftAutoCommitTest, TestFastLRU= Cache, UniqFieldsUpdateProcessorFactoryTest, TestQuerySenderNoQuery, TestJs= onRequest, TestCSVResponseWriter, TestRandomFaceting, RemoteQueryErrorTest,= TestPivotHelperCode, ExternalFileFieldSortTest, TestXIncludeConfig, TestSe= archerReuse, TestMultiValuedNumericRangeQuery, TestCSVLoader, DistribJoinFr= omCollectionTest, PreAnalyzedFieldTest, BlockCacheTest, TestReplicationHand= lerDiskOverFlow, CdcrReplicationHandlerTest, TestComplexPhraseQParserPlugin= , ZkStateWriterTest, ZookeeperStatusHandlerTest, TestCloudJSONFacetJoinDoma= in, TestLeaderElectionWithEmptyReplica, SolrCoreTest, TestManagedSchema, Ba= sicFunctionalityTest, TestCloudSearcherWarming, TestRemoteStreaming, Direct= SolrSpellCheckerTest, TestCollectionsAPIViaSolrCloudCluster, CoreAdminHandl= erTest, SolrCloudReportersTest, PeerSyncTest, ExecutePlanActionTest, Atomic= UpdateRemovalJavabinTest, NodeLostTriggerTest, DocumentAnalysisRequestHandl= erTest, TriggerIntegrationTest, TestSSLRandomization, RegexBytesRefFilterTe= st, TestSha256AuthenticationProvider, ZkCLITest, CircularListTest, OpenExch= angeRatesOrgProviderTest, MoreLikeThisHandlerTest, HdfsRestartWhileUpdating= Test, NodeAddedTriggerIntegrationTest, V2StandaloneTest, TestOrdValues, Tes= tConfigSetsAPIExclusivity, ScriptEngineTest, HdfsDirectoryTest, AuditLogger= IntegrationTest, LargeFieldTest, FullHLLTest, SparseHLLTest, LoggingHandler= Test, TestIndexingPerformance, BigEndianAscendingWordSerializerTest, QueryR= esultKeyTest, TestStressInPlaceUpdates, TestCloudPseudoReturnFields, Securi= tyConfHandlerTest, TestAddFieldRealTimeGet, TestMaxScoreQueryParser, TestSi= mExecutePlanAction, MinimalSchemaTest, TestDistributedSearch, JSONWriterTes= t, TestSimpleTextCodec, TestBulkSchemaConcurrent, TestMergePolicyConfig, Ex= itableDirectoryReaderTest, DistributedFacetPivotWhiteBoxTest, IndexSizeTrig= gerTest, ConnectionReuseTest, TestGraphMLResponseWriter, MultiDestinationAu= ditLoggerTest, WrapperMergePolicyFactoryTest, InfixSuggestersTest, TestUseD= ocValuesAsStored2, TestOmitPositions, VersionInfoTest, MultiThreadedOCPTest= , TestDownShardTolerantSearch, TestReplicaProperties, FullSolrCloudDistribC= mdsTest, CdcrWithNodesRestartsTest, HdfsNNFailoverTest, StatsComponentTest,= TestEmbeddedSolrServerConstructors, TestDocTermOrdsUninvertLimit, TestNume= ricTokenStream, HdfsTlogReplayBufferedWhileIndexingTest, DistributedQueryCo= mponentOptimizationTest, TestJsonFacets, TestRealTimeGet, BasicDistributedZ= kTest, AdminHandlersProxyTest, TestCoreContainer, TestFieldResource, TestAu= thenticationFramework, SolrInfoBeanTest, TestNestedDocsSort, SortByFunction= Test, TestMiniSolrCloudClusterSSL, TestManagedStopFilterFactory, BasicAuthS= tandaloneTest, TestHighlightDedupGrouping, CoreMergeIndexesAdminHandlerTest= , TestCodecSupport, AnalysisErrorHandlingTest, TestHdfsBackupRestoreCore, D= isMaxRequestHandlerTest, StressHdfsTest, LeaderElectionIntegrationTest, BJQ= ParserTest, TestLMJelinekMercerSimilarityFactory, TestObjectReleaseTracker,= ClassificationUpdateProcessorFactoryTest, TestSolrConfigHandlerConcurrent,= TestReload, TestSimLargeCluster, ClusterStateTest, TestRetrieveFieldsOptim= izer, OverseerTaskQueueTest, TestExactSharedStatsCache, SolrPluginUtilsTest= , TestValueSourceCache, ConcurrentDeleteAndCreateCollectionTest, MetricsCon= figTest, MetricsHistoryIntegrationTest, MigrateRouteKeyTest, OverseerModify= CollectionTest, RecoveryAfterSoftCommitTest, ReplicationFactorTest, SyncSli= ceTest, TestRebalanceLeaders, TestSolrCloudWithDelegationTokens, TestSolrCl= oudWithKerberosAlt, TestWithCollection, HdfsCollectionsAPIDistributedZkTest= , ShardSplitTest, ConfigureRecoveryStrategyTest, CoreSorterTest, RequestHan= dlersTest, ResourceLoaderTest, SOLR749Test, TestConfigOverlay, TestConfigSe= tImmutable, TestCustomStream, BinaryUpdateRequestHandlerTest, RequestLoggin= gTest, SearchHandlerTest, TestSQLHandler, SystemInfoHandlerTest, TestCollec= tionAPIs, CustomHighlightComponentTest, DebugComponentTest, DistributedDebu= gComponentTest, DistributedExpandComponentTest, DistributedFacetPivotLongTa= ilTest, DistributedSuggestComponentTest, QueryElevationComponentTest, Shuff= lingReplicaListTransformerTest, SuggestComponentTest, TermVectorComponentTe= st, TermsComponentTest, TestExpandComponent, TestExportWriter, JavabinLoade= rTest, EmbeddedSolrNoSerializeTest, Tagger2Test, TaggerTest, XmlInterpolati= onTest, HighlighterConfigTest, HighlighterTest, TestSlowCompositeReaderWrap= per, TestLegacyNumericUtils, SolrGraphiteReporterTest, SubstringBytesRefFil= terTest, TestFacetMethods, TestFaceting, TestUnInvertedFieldException, Test= WriterPerf, TestChildDocTransformer, TestSubQueryTransformerCrossCore, Test= ManagedResource, TestRestManager, TestBulkSchemaAPI, CurrencyFieldTypeTest,= DocValuesMultiTest, IndexSchemaTest, NotRequiredUniqueKeyTest, SpatialRPTF= ieldTypeTest, TestCollationField, TestCollationFieldDocValues, TestSchemaMa= nager, TestSchemalessBufferedUpdates, AnalyticsMergeStrategyTest, QueryEqua= lityTest, QueryParsingTest, ReturnFieldsTest, TestCustomSort, TestDocSet, T= estFilteredDocIdSet, TestFiltering, TestLFUCache, TestSolr4Spatial, TestSol= rJ, TestSort, TestStressUserVersions, TestStressVersions, DistributedFacetS= impleRefinementLongTailTest, TestBM25SimilarityFactory, TestDFRSimilarityFa= ctory, TestPerFieldSimilarity, TestSweetSpotSimilarityFactory, SolrLogAudit= LoggerPluginTest, TestPKIAuthenticationPlugin, TestImpersonationWithHadoopA= uth, TestSolrCloudWithHadoopAuthPlugin, TestZkAclsWithHadoopAuth, NoCacheHe= aderTest, SolrRequestParserTest, SpellCheckCollatorTest, SuggesterFSTTest, = SuggesterTest, TestAnalyzedSuggestions, TestFreeTextSuggestions, TestFieldC= acheVsDocValues, DirectUpdateHandlerTest, PeerSyncWithLeaderTest] [junit4] Completed [849/855 (3!)] on J1 in 140.35s, 1 test, 1 failure <<= < FAILURES! [...truncated 8993 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.x= ml:643: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.x= ml:128: Found 1 violations in source files (File uses Mockito but has no as= sumeWorkingMockito() call). Total time: 103 minutes 15 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_6664_1162174080.1556696819786 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_6664_1162174080.1556696819786--