You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/05/08 06:10:19 UTC
[JENKINS] Lucene-Solr-8.x-Windows (32bit/jdk1.8.0_201) - Build #
242 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/242/
Java: 32bit/jdk1.8.0_201 -client -XX:+UseConcMarkSweepGC
6 tests failed.
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
Error from server at http://127.0.0.1:53953/solr/authCollection: Error from server at null: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 401 require authentication</title> </head> <body><h2>HTTP ERROR 401</h2> <p>Problem accessing /solr/authCollection_shard2_replica_n2/select. Reason: <pre> require authentication</pre></p><hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.14.v20181114</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53953/solr/authCollection: Error from server at null: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
<title>Error 401 require authentication</title>
</head>
<body><h2>HTTP ERROR 401</h2>
<p>Problem accessing /solr/authCollection_shard2_replica_n2/select. Reason:
<pre> require authentication</pre></p><hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.14.v20181114</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([110350F33EE05ACA:AD6D26E19AB3D9B0]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1068)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:837)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:769)
at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:290)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed
Error Message:
Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=180537600, failWrongCredentials=0, requestTimes=90, requests=4, errors=0}
Stack Trace:
java.lang.AssertionError: Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=180537600, failWrongCredentials=0, requestTimes=90, requests=4, errors=0}
at __randomizedtesting.SeedInfo.seed([110350F33EE05ACA:172B529D5C57CC79]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinimums(SolrCloudAuthTestCase.java:74)
at org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed
Error Message:
Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=11502800, failWrongCredentials=0, requestTimes=218, requests=4, errors=0}
Stack Trace:
java.lang.AssertionError: Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=11502800, failWrongCredentials=0, requestTimes=218, requests=4, errors=0}
at __randomizedtesting.SeedInfo.seed([110350F33EE05ACA:172B529D5C57CC79]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinimums(SolrCloudAuthTestCase.java:74)
at org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed
Error Message:
Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=14175900, failWrongCredentials=0, requestTimes=299, requests=4, errors=0}
Stack Trace:
java.lang.AssertionError: Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=14175900, failWrongCredentials=0, requestTimes=299, requests=4, errors=0}
at __randomizedtesting.SeedInfo.seed([110350F33EE05ACA:172B529D5C57CC79]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinimums(SolrCloudAuthTestCase.java:74)
at org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed
Error Message:
Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=20733000, failWrongCredentials=0, requestTimes=296, requests=4, errors=0}
Stack Trace:
java.lang.AssertionError: Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=20733000, failWrongCredentials=0, requestTimes=296, requests=4, errors=0}
at __randomizedtesting.SeedInfo.seed([110350F33EE05ACA:172B529D5C57CC79]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinimums(SolrCloudAuthTestCase.java:74)
at org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed
Error Message:
Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=14605100, failWrongCredentials=0, requestTimes=204, requests=4, errors=0}
Stack Trace:
java.lang.AssertionError: Expected metric minimums for prefix SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: {failMissingCredentials=0, authenticated=4, passThrough=0, totalTime=14605100, failWrongCredentials=0, requestTimes=204, requests=4, errors=0}
at __randomizedtesting.SeedInfo.seed([110350F33EE05ACA:172B529D5C57CC79]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129)
at org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinimums(SolrCloudAuthTestCase.java:74)
at org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 15638 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\init-core-data-001
[junit4] 2> 5762655 INFO (SUITE-BasicAuthIntegrationTest-seed#[110350F33EE05ACA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 5762671 INFO (SUITE-BasicAuthIntegrationTest-seed#[110350F33EE05ACA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 5762671 INFO (SUITE-BasicAuthIntegrationTest-seed#[110350F33EE05ACA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 5762671 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth
[junit4] 2> 5762671 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001
[junit4] 2> 5762671 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 5762671 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 5762671 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 5762780 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer start zk server on port:53934
[junit4] 2> 5762780 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53934
[junit4] 2> 5762780 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53934
[junit4] 2> 5762780 INFO (zkConnectionManagerCallback-23230-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5762796 INFO (zkConnectionManagerCallback-23232-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5762796 INFO (zkConnectionManagerCallback-23234-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5762796 WARN (jetty-launcher-23235-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 5762796 WARN (jetty-launcher-23235-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12cf93f{/solr,null,AVAILABLE}
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@199b994{/solr,null,AVAILABLE}
[junit4] 2> 5762796 WARN (jetty-launcher-23235-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@9a0b45{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:53945}
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.e.j.s.Server Started @5762884ms
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53945}
[junit4] 2> 5762796 ERROR (jetty-launcher-23235-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T05:26:19.557Z
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 5762796 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1096b37{/solr,null,AVAILABLE}
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@11479cf{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:53948}
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.e.j.s.Server Started @5762887ms
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53948}
[junit4] 2> 5762812 ERROR (jetty-launcher-23235-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T05:26:19.573Z
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@fffcb4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:53953}
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.e.j.s.Server Started @5762890ms
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=53953}
[junit4] 2> 5762812 ERROR (jetty-launcher-23235-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T05:26:19.573Z
[junit4] 2> 5762812 INFO (zkConnectionManagerCallback-23237-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5762812 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 5762827 INFO (zkConnectionManagerCallback-23239-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5762827 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 5763030 INFO (zkConnectionManagerCallback-23241-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5763030 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 5764562 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5764577 WARN (jetty-launcher-23235-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1da059e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5764593 WARN (jetty-launcher-23235-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1dffcbd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5764593 INFO (jetty-launcher-23235-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53934/solr
[junit4] 2> 5764609 INFO (zkConnectionManagerCallback-23250-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5764609 INFO (zkConnectionManagerCallback-23252-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5764765 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53948_solr
[junit4] 2> 5764765 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.c.Overseer Overseer (id=72081562748256263-127.0.0.1:53948_solr-n_0000000000) starting
[junit4] 2> 5764780 INFO (zkConnectionManagerCallback-23259-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5764780 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53934/solr ready
[junit4] 2> 5764780 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53948_solr
[junit4] 2> 5764797 INFO (zkCallback-23258-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 5764797 INFO (zkCallback-23251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 5764827 INFO (OverseerStateUpdate-72081562748256263-127.0.0.1:53948_solr-n_0000000000) [n:127.0.0.1:53948_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:53948_solr
[junit4] 2> 5764893 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 5765001 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53948.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5765014 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53948.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5765014 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53948.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5765014 INFO (jetty-launcher-23235-thread-3) [n:127.0.0.1:53948_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node3\.
[junit4] 2> 5767093 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5767218 WARN (jetty-launcher-23235-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1ce5781[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5767312 WARN (jetty-launcher-23235-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@86d49e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5767327 INFO (jetty-launcher-23235-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53934/solr
[junit4] 2> 5767327 INFO (zkConnectionManagerCallback-23266-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5767327 INFO (zkConnectionManagerCallback-23268-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5767327 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 5767343 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:53945_solr as DOWN
[junit4] 2> 5767343 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 5767343 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53945_solr
[junit4] 2> 5767343 INFO (zkCallback-23258-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 5767343 INFO (zkCallback-23251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 5767421 INFO (zkCallback-23267-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 5767421 INFO (zkConnectionManagerCallback-23275-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5767421 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 5767421 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53934/solr ready
[junit4] 2> 5767421 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 5767469 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53945.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5767484 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53945.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5767484 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53945.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5767484 INFO (jetty-launcher-23235-thread-2) [n:127.0.0.1:53945_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node2\.
[junit4] 2> 5767952 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5767952 WARN (jetty-launcher-23235-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@bafe13[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5767968 WARN (jetty-launcher-23235-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@13c9b3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5767968 INFO (jetty-launcher-23235-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53934/solr
[junit4] 2> 5767968 INFO (zkConnectionManagerCallback-23282-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5767968 INFO (zkConnectionManagerCallback-23284-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5767986 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 5767986 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:53953_solr as DOWN
[junit4] 2> 5767999 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 5767999 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53953_solr
[junit4] 2> 5767999 INFO (zkCallback-23258-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 5767999 INFO (zkCallback-23251-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 5767999 INFO (zkCallback-23267-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 5767999 INFO (zkCallback-23283-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 5767999 INFO (zkCallback-23274-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 5768061 INFO (zkConnectionManagerCallback-23291-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5768077 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 5768077 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53934/solr ready
[junit4] 2> 5768077 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 5768140 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53953.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5768156 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53953.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5768156 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53953.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5768156 INFO (jetty-launcher-23235-thread-1) [n:127.0.0.1:53953_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node1\.
[junit4] 2> 5768233 INFO (zkConnectionManagerCallback-23297-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 5768233 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 5768233 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53934/solr ready
[junit4] 2> 5768233 INFO (qtp28522317-63504) [n:127.0.0.1:53945_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 5768249 INFO (OverseerThreadFactory-20461-thread-1-processing-n:127.0.0.1:53948_solr) [n:127.0.0.1:53948_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 5768358 INFO (OverseerStateUpdate-72081562748256263-127.0.0.1:53948_solr-n_0000000000) [n:127.0.0.1:53948_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"authCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53945/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 5768358 INFO (OverseerStateUpdate-72081562748256263-127.0.0.1:53948_solr-n_0000000000) [n:127.0.0.1:53948_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"authCollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53948/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 5768358 INFO (OverseerStateUpdate-72081562748256263-127.0.0.1:53948_solr-n_0000000000) [n:127.0.0.1:53948_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"authCollection_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:53953/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 5768577 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr x:authCollection_shard3_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 5768577 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr x:authCollection_shard2_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 5768577 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr x:authCollection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 5768577 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr x:authCollection_shard2_replica_n2] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 5769624 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 5769624 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 5769640 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.s.IndexSchema [authCollection_shard3_replica_n4] Schema name=minimal
[junit4] 2> 5769640 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 5769640 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 5769640 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 5769640 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n4' using configuration from collection authCollection, trusted=true
[junit4] 2> 5769640 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 5769640 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1' using configuration from collection authCollection, trusted=true
[junit4] 2> 5769655 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53945.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5769655 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53953.solr.core.authCollection.shard3.replica_n4' (registry 'solr.core.authCollection.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5769655 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node2\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node2\.\authCollection_shard1_replica_n1\data\]
[junit4] 2> 5769655 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SolrCore [[authCollection_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node1\authCollection_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node1\.\authCollection_shard3_replica_n4\data\]
[junit4] 2> 5769671 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.s.IndexSchema [authCollection_shard2_replica_n2] Schema name=minimal
[junit4] 2> 5769671 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 5769671 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n2' using configuration from collection authCollection, trusted=true
[junit4] 2> 5769671 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_53948.solr.core.authCollection.shard2.replica_n2' (registry 'solr.core.authCollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@142a711
[junit4] 2> 5769671 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.SolrCore [[authCollection_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node3\authCollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_110350F33EE05ACA-001\tempDir-001\node3\.\authCollection_shard2_replica_n2\data\]
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@cfc86e[authCollection_shard2_replica_n2] main]
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13e6b60[authCollection_shard1_replica_n1] main]
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@1021e2d[authCollection_shard3_replica_n4] main]
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 5769796 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1632940260387192832
[junit4] 2> 5769796 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1632940260387192832
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 5769796 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1632940260387192832
[junit4] 2> 5769811 INFO (searcherExecutor-20480-thread-1-processing-n:127.0.0.1:53953_solr x:authCollection_shard3_replica_n4 c:authCollection s:shard3 r:core_node6) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SolrCore [authCollection_shard3_replica_n4] Registered new searcher Searcher@1021e2d[authCollection_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5769811 INFO (searcherExecutor-20481-thread-1-processing-n:127.0.0.1:53945_solr x:authCollection_shard1_replica_n1 c:authCollection s:shard1 r:core_node3) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher Searcher@13e6b60[authCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5769811 INFO (searcherExecutor-20482-thread-1-processing-n:127.0.0.1:53948_solr x:authCollection_shard2_replica_n2 c:authCollection s:shard2 r:core_node5) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.SolrCore [authCollection_shard2_replica_n2] Registered new searcher Searcher@cfc86e[authCollection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard2
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard1
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard3 to Terms{values={core_node6=0}, version=0}
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard3
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53948/solr/authCollection_shard2_replica_n2/
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53945/solr/authCollection_shard1_replica_n1/
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53953/solr/authCollection_shard3_replica_n4/
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.SyncStrategy http://127.0.0.1:53948/solr/authCollection_shard2_replica_n2/ has no replicas
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard2/leader after winning as /collections/authCollection/leader_elect/shard2/election/72081562748256263-core_node5-n_0000000000
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:53945/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard1/leader after winning as /collections/authCollection/leader_elect/shard1/election/72081562748256266-core_node3-n_0000000000
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.SyncStrategy http://127.0.0.1:53953/solr/authCollection_shard3_replica_n4/ has no replicas
[junit4] 2> 5769811 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard3/leader after winning as /collections/authCollection/leader_elect/shard3/election/72081562748256269-core_node6-n_0000000000
[junit4] 2> 5769811 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:53948/solr/authCollection_shard2_replica_n2/ shard2
[junit4] 2> 5769811 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:53945/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 5769827 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:53953/solr/authCollection_shard3_replica_n4/ shard3
[junit4] 2> 5769937 INFO (zkCallback-23283-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5769937 INFO (zkCallback-23251-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5769937 INFO (zkCallback-23267-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5769937 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 5769937 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 5769937 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 5769937 INFO (qtp19183998-63522) [n:127.0.0.1:53953_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1365
[junit4] 2> 5769937 INFO (qtp10529399-63513) [n:127.0.0.1:53948_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1365
[junit4] 2> 5769937 INFO (qtp28522317-63507) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1366
[junit4] 2> 5769951 INFO (zkCallback-23283-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5769952 INFO (zkCallback-23267-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5769952 INFO (zkCallback-23251-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5769952 INFO (qtp28522317-63504) [n:127.0.0.1:53945_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 5770046 INFO (zkCallback-23251-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23251-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23267-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23267-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23283-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23283-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (qtp28522317-63504) [n:127.0.0.1:53945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2} status=0 QTime=1804
[junit4] 2> 5770046 INFO (zkCallback-23251-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23267-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (zkCallback-23283-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [3])
[junit4] 2> 5770046 INFO (qtp28522317-63509) [n:127.0.0.1:53945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0
[junit4] 2> 5770046 INFO (qtp28522317-63519) [n:127.0.0.1:53945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0
[junit4] 2> 5770046 INFO (zkCallback-23251-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 5770046 INFO (zkCallback-23283-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 5770046 INFO (zkCallback-23251-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 5770046 INFO (zkCallback-23251-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 5770046 INFO (zkCallback-23267-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 5770046 INFO (zkCallback-23267-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 5770046 INFO (zkCallback-23283-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 5770046 INFO (zkCallback-23267-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 5770046 INFO (zkCallback-23283-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 5770124 INFO (qtp28522317-63531) [n:127.0.0.1:53945_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0
[junit4] 2> 5770124 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=20004120
[junit4] 2> 5770124 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:53945_solr
[junit4] 2> 5770124 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 5770124 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:53945_solr as DOWN
[junit4] 2> 5770124 INFO (coreCloseExecutor-20495-thread-1) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@932492
[junit4] 2> 5770124 INFO (coreCloseExecutor-20495-thread-1) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard1.replica_n1, tag=932492
[junit4] 2> 5770124 INFO (coreCloseExecutor-20495-thread-1) [n:127.0.0.1:53945_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b26143: rootName = solr_53945, domain = solr.core.authCollection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.authCollection.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@404f34
[junit4] 2> 5770139 INFO (coreCloseExecutor-20495-thread-1) [n:127.0.0.1:53945_solr c:authCollection s
[...truncated too long message...]
y-closer-674-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 153782 INFO (jetty-closer-674-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@148b59e: rootName = solr_56738, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@c16277
[junit4] 2> 153785 INFO (jetty-closer-674-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 153785 INFO (jetty-closer-674-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1feda83: rootName = solr_56737, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1b9cc43
[junit4] 2> 153785 INFO (jetty-closer-674-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 153789 INFO (jetty-closer-674-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72081729913749510-127.0.0.1:56737_solr-n_0000000000) closing
[junit4] 2> 153793 INFO (OverseerStateUpdate-72081729913749510-127.0.0.1:56737_solr-n_0000000000) [n:127.0.0.1:56737_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:56737_solr
[junit4] 2> 153798 INFO (zkCallback-648-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 153799 INFO (zkCallback-657-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 153799 INFO (zkCallback-664-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 153799 INFO (zkCallback-657-thread-3) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56738_solr
[junit4] 2> 153802 INFO (OverseerAutoScalingTriggerThread-72081729913749510-127.0.0.1:56737_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 153805 INFO (closeThreadPool-681-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72081729913749510-127.0.0.1:56737_solr-n_0000000000) closing
[junit4] 2> 153806 INFO (closeThreadPool-681-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72081729913749510-127.0.0.1:56737_solr-n_0000000000) closing
[junit4] 2> 153810 INFO (jetty-closer-674-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 153810 INFO (jetty-closer-674-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aba959: rootName = solr_56738, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1f0de4e
[junit4] 2> 153811 INFO (jetty-closer-674-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@15ba185{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 153813 INFO (jetty-closer-674-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@8dab06{/solr,null,UNAVAILABLE}
[junit4] 2> 153814 INFO (jetty-closer-674-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 153815 INFO (jetty-closer-674-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 153816 INFO (jetty-closer-674-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3ade92: rootName = solr_56738, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1b9cc43
[junit4] 2> 153816 INFO (jetty-closer-674-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 153824 INFO (jetty-closer-674-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@febd5d{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 153825 INFO (jetty-closer-674-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@165cd61{/solr,null,UNAVAILABLE}
[junit4] 2> 153825 INFO (jetty-closer-674-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 153826 INFO (TEST-JWTAuthPluginIntegrationTest.testMetrics-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 153827 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10015f3924e0000, likely client has closed socket
[junit4] 2> 153828 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 2 /solr/configs/jwtColl.AUTOCREATED
[junit4] 2> 2 /solr/collections/jwtColl/terms/shard2
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/jwtColl.AUTOCREATED/managed-schema
[junit4] 2> 2 /solr/collections/jwtColl/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/collections/jwtColl/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 153840 INFO (TEST-JWTAuthPluginIntegrationTest.testMetrics-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:56726
[junit4] 2> 153840 INFO (TEST-JWTAuthPluginIntegrationTest.testMetrics-seed#[110350F33EE05ACA]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 56726
[junit4] 2> 154842 INFO (TEST-JWTAuthPluginIntegrationTest.testMetrics-seed#[110350F33EE05ACA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMetrics
[junit4] OK 10.4s J0 | JWTAuthPluginIntegrationTest.testMetrics
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.security.JWTAuthPluginIntegrationTest_110350F33EE05ACA-003
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=1485, maxMBSortInHeap=6.4619368748201875, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@10405b7), locale=ja-JP, timezone=America/Santa_Isabel
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_201 (32-bit)/cpus=3,threads=1,free=36464976,total=90050560
[junit4] 2> NOTE: All tests run in this JVM: [JWTAuthPluginIntegrationTest, BasicAuthIntegrationTest, JWTAuthPluginIntegrationTest, JWTAuthPluginIntegrationTest]
[junit4] Completed [8/10 (5!)] on J0 in 25.65s, 3 tests, 1 failure <<< FAILURES!
[...truncated 22 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1573: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1100: There were test failures: 10 suites, 20 tests, 5 failures [seed: 110350F33EE05ACA]
Total time: 2 minutes 55 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.security.BasicAuthIntegrationTest
[repro] 5/5 failed: org.apache.solr.security.JWTAuthPluginIntegrationTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=31986798e8a03802f3af5999067038647bb7c764, workspace=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows
[WARNINGS] Computing warning deltas based on reference build #241
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-Windows (32bit/jdk1.8.0_201) - Build #
243 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/243/
Java: 32bit/jdk1.8.0_201 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.TestLBHttp2SolrClient.testReliability
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:54195/solr/collection1/select?q=*%3A*&wt=javabin&version=2
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:54195/solr/collection1/select?q=*%3A*&wt=javabin&version=2
at __randomizedtesting.SeedInfo.seed([3125228B40FDD0C:C2DA8F6E15690CA5]:0)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:406)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:739)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:605)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:581)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:987)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1002)
at org.apache.solr.client.solrj.TestLBHttp2SolrClient.testReliability(TestLBHttp2SolrClient.java:223)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.util.concurrent.TimeoutException
at org.eclipse.jetty.client.util.InputStreamResponseListener.get(InputStreamResponseListener.java:216)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:397)
... 46 more
Build Log:
[...truncated 16419 lines...]
[junit4] Suite: org.apache.solr.client.solrj.TestLBHttp2SolrClient
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\init-core-data-001
[junit4] 2> 14944 INFO (SUITE-TestLBHttp2SolrClient-seed#[3125228B40FDD0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 15377 INFO (SUITE-TestLBHttp2SolrClient-seed#[3125228B40FDD0C]-worker) [ ] o.e.j.u.log Logging initialized @15389ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 15424 INFO (SUITE-TestLBHttp2SolrClient-seed#[3125228B40FDD0C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 15696 INFO (SUITE-TestLBHttp2SolrClient-seed#[3125228B40FDD0C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 15732 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReliability
[junit4] 2> 17036 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@9711c7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 17463 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 18032 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 18048 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 18073 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 18117 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 18117 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 18137 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 18235 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@eedf80{/solr,null,AVAILABLE}
[junit4] 2> 18251 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14c9c52{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54195}
[junit4] 2> 18251 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server Started @18298ms
[junit4] 2> 18251 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\collection1\data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=54195}
[junit4] 2> 18966 ERROR (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 18966 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 18970 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 18982 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 18984 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 18984 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T17:49:10.527Z
[junit4] 2> 19160 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 19188 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\solr.xml
[junit4] 2> 19423 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 22584 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 22614 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@431d53[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 24269 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@27a2f8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 26274 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 26307 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 27068 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 27077 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 27077 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 27600 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\.
[junit4] 2> 27600 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 27935 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 28245 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 28741 WARN (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 28946 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 28953 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\.\collection1, trusted=true
[junit4] 2> 29521 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 29820 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\.\collection1\data\]
[junit4] 2> 37274 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 37280 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 37426 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ef5677[collection1] main]
[junit4] 2> 37515 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\collection1\conf
[junit4] 2> 37801 INFO (coreLoadExecutor-9-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 37812 INFO (searcherExecutor-10-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ef5677[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 40212 INFO (qtp28255958-70) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 1042
[junit4] 2> 40498 INFO (qtp28255958-66) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 40498 INFO (qtp28255958-66) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@19752fd commitCommandVersion:0
[junit4] 2> 42357 INFO (qtp28255958-66) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ae2cc5[collection1] main]
[junit4] 2> 42365 INFO (qtp28255958-66) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42385 INFO (searcherExecutor-10-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ae2cc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C10:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1557337773199}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 42394 INFO (qtp28255958-66) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1896
[junit4] 2> 42446 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 42446 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@134d7fe{/solr,null,AVAILABLE}
[junit4] 2> 42476 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1ecb12c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54205}
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server Started @42525ms
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\collection1\data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=54205}
[junit4] 2> 42477 ERROR (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 42477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T17:49:34.020Z
[junit4] 2> 42481 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\solr.xml
[junit4] 2> 42526 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 43837 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 43847 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@e79cd5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 43877 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@a571fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 44172 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 44172 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 44296 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 44334 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 44334 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 44369 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\.
[junit4] 2> 44369 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 44736 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 44808 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 44825 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 44825 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\.\collection1, trusted=true
[junit4] 2> 44825 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 44826 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\.\collection1\data\]
[junit4] 2> 45406 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 45406 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 45414 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ae97e[collection1] main]
[junit4] 2> 45420 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\collection1\conf
[junit4] 2> 45426 INFO (coreLoadExecutor-22-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 45434 INFO (searcherExecutor-23-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ae97e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 45449 INFO (qtp15953731-94) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 9
[junit4] 2> 45451 INFO (qtp15953731-92) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 45451 INFO (qtp15953731-92) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7d0993 commitCommandVersion:0
[junit4] 2> 45657 INFO (qtp15953731-92) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9567c1[collection1] main]
[junit4] 2> 45662 INFO (searcherExecutor-23-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9567c1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C10:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1557337777020}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 45663 INFO (qtp15953731-92) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 45663 INFO (qtp15953731-92) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 214
[junit4] 2> 45687 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 45688 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 45688 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 45688 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 45690 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 45690 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 45690 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 45691 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f6526{/solr,null,AVAILABLE}
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@422a78{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54220}
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server Started @45774ms
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\collection1\data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=54220}
[junit4] 2> 45727 ERROR (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 45727 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T17:49:37.270Z
[junit4] 2> 45733 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\solr.xml
[junit4] 2> 45759 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 47954 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 47961 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@138e382[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 47984 WARN (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@64dadb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 48247 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 48247 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 48445 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 48519 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 48519 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 48542 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\.
[junit4] 2> 48542 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 48815 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 48846 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 48857 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 48857 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\.\collection1, trusted=true
[junit4] 2> 48858 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 48859 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\.\collection1\data\]
[junit4] 2> 49213 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 49213 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 49228 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b2b5b7[collection1] main]
[junit4] 2> 49229 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\collection1\conf
[junit4] 2> 49229 INFO (coreLoadExecutor-35-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 49238 INFO (searcherExecutor-36-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b2b5b7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 49268 INFO (qtp33309014-116) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 22
[junit4] 2> 49272 INFO (qtp33309014-116) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 49272 INFO (qtp33309014-116) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1ccd91d commitCommandVersion:0
[junit4] 2> 49511 INFO (qtp33309014-116) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a3ca72[collection1] main]
[junit4] 2> 49512 INFO (qtp33309014-116) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 49516 INFO (searcherExecutor-36-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a3ca72[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C10:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1557337780850}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 49516 INFO (qtp33309014-116) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 244
[junit4] 2> 49520 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=13807804
[junit4] 2> 49523 INFO (coreCloseExecutor-40-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1cc3a0c
[junit4] 2> 49523 INFO (coreCloseExecutor-40-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1cc3a0c
[junit4] 2> 49525 INFO (coreCloseExecutor-40-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@187da86: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@180c6a8
[junit4] 2> 49851 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 49853 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@109eca5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@f1f623
[junit4] 2> 49872 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 49872 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60e29: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 49874 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 49874 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@dcb01b: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 49918 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1ecb12c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 49927 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@134d7fe{/solr,null,UNAVAILABLE}
[junit4] 2> 49927 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 52077 INFO (qtp28255958-70) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=897
[junit4] 2> 52197 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=239196
[junit4] 2> 52201 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 52201 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c733fe: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1855356
[junit4] 2> 52222 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 52222 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@208a73: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 52227 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 52227 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@475f76: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 52257 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@14c9c52{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 52257 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@eedf80{/solr,null,UNAVAILABLE}
[junit4] 2> 52257 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 52278 INFO (qtp28255958-70) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e451e1
[junit4] 2> 52278 INFO (qtp28255958-70) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=e451e1
[junit4] 2> 52278 INFO (qtp28255958-70) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ecc72f: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1025504
[junit4] 2> 52396 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2322272
[junit4] 2> 52406 INFO (coreCloseExecutor-44-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7518ae
[junit4] 2> 52406 INFO (coreCloseExecutor-44-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7518ae
[junit4] 2> 52406 INFO (coreCloseExecutor-44-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b9b04: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@149a2c1
[junit4] 2> 52468 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 52468 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@27fbd5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@a6c780
[junit4] 2> 52477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 52477 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@17a93a: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 52478 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 52478 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@136b66d: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 52482 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@422a78{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 52482 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f6526{/solr,null,UNAVAILABLE}
[junit4] 2> 52482 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 52508 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[3125228B40FDD0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReliability
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLBHttp2SolrClient -Dtests.method=testReliability -Dtests.seed=3125228B40FDD0C -Dtests.slow=true -Dtests.locale=es-HN -Dtests.timezone=Europe/Lisbon -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 36.8s J1 | TestLBHttp2SolrClient.testReliability <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:54195/solr/collection1/select?q=*%3A*&wt=javabin&version=2
[junit4] > at __randomizedtesting.SeedInfo.seed([3125228B40FDD0C:C2DA8F6E15690CA5]:0)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:406)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:739)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:605)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:581)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:987)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1002)
[junit4] > at org.apache.solr.client.solrj.TestLBHttp2SolrClient.testReliability(TestLBHttp2SolrClient.java:223)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.util.concurrent.TimeoutException
[junit4] > at org.eclipse.jetty.client.util.InputStreamResponseListener.get(InputStreamResponseListener.java:216)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:397)
[junit4] > ... 46 more
[junit4] 2> 52577 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTwoServers
[junit4] 2> 52594 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1c02aed[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 52614 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 52615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 52615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 52615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 52615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 52615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 52615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 52617 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12852e1{/solr,null,AVAILABLE}
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@91875b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54232}
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server Started @52685ms
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\collection1\data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=54232}
[junit4] 2> 52638 ERROR (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T17:49:44.181Z
[junit4] 2> 52638 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\solr.xml
[junit4] 2> 52674 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 53006 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 53015 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@195ff24[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 53028 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1a50aab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 53349 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 53349 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 53418 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 53451 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 53451 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 53453 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\.
[junit4] 2> 53453 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 53578 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 53607 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 53701 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 53701 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\.\collection1, trusted=true
[junit4] 2> 53703 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 53703 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\.\collection1\data\]
[junit4] 2> 54052 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 54052 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 54056 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a518d[collection1] main]
[junit4] 2> 54056 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-0-001\collection1\conf
[junit4] 2> 54060 INFO (coreLoadExecutor-54-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 54061 INFO (searcherExecutor-55-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3a518d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 54588 INFO (qtp32355531-164) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 4
[junit4] 2> 54597 INFO (qtp32355531-164) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 54597 INFO (qtp32355531-164) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7a9d6 commitCommandVersion:0
[junit4] 2> 54724 INFO (qtp32355531-164) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4bd77c[collection1] main]
[junit4] 2> 54731 INFO (qtp32355531-164) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 54732 INFO (searcherExecutor-55-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4bd77c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C10:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1557337786170}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 54732 INFO (qtp32355531-164) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 138
[junit4] 2> 54748 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 54748 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 54748 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 54748 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 54764 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 54764 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 54764 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 54765 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ce09b4{/solr,null,AVAILABLE}
[junit4] 2> 54774 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@b703f9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54240}
[junit4] 2> 54774 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server Started @54823ms
[junit4] 2> 54774 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\collection1\data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=54240}
[junit4] 2> 54776 ERROR (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 54776 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 54776 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 54776 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 54776 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 54776 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T17:49:46.319Z
[junit4] 2> 54781 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\solr.xml
[junit4] 2> 54797 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 57014 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 57029 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@14a1278[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57063 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@c4dfa9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 57206 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 57206 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 57263 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 57279 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 57282 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 57306 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\.
[junit4] 2> 57307 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 57440 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 57462 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 57479 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 57479 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\.\collection1, trusted=true
[junit4] 2> 57479 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 57479 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\.\collection1\data\]
[junit4] 2> 57697 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 57697 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 57702 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@deafdc[collection1] main]
[junit4] 2> 57704 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-1-001\collection1\conf
[junit4] 2> 57708 INFO (coreLoadExecutor-67-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 57712 INFO (searcherExecutor-68-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@deafdc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 58266 INFO (qtp10173840-186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 13
[junit4] 2> 58269 INFO (qtp10173840-186) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 58269 INFO (qtp10173840-186) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@983907 commitCommandVersion:0
[junit4] 2> 58580 INFO (qtp10173840-186) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@cda738[collection1] main]
[junit4] 2> 58584 INFO (searcherExecutor-68-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cda738[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C10:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1557337789930}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 58587 INFO (qtp10173840-186) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 58587 INFO (qtp10173840-186) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 317
[junit4] 2> 58601 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 58603 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 58603 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 58603 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 58603 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 58603 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 58604 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 58604 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b70112{/solr,null,AVAILABLE}
[junit4] 2> 58613 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14e30ee{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54257}
[junit4] 2> 58614 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.Server Started @58661ms
[junit4] 2> 58614 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\collection1\data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=54257}
[junit4] 2> 58614 ERROR (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 58614 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 58615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 58615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 58615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 58615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-08T17:49:50.158Z
[junit4] 2> 58615 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\solr.xml
[junit4] 2> 58635 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 60600 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 60612 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@18d84d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 60623 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@713d92[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 60799 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 60799 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 60868 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 60886 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 60886 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 60897 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\.
[junit4] 2> 60897 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 60976 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 61015 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 61020 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 61020 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\.\collection1, trusted=true
[junit4] 2> 61024 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1df3355
[junit4] 2> 61024 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\.\collection1\data\]
[junit4] 2> 61325 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 61327 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 61360 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@510807[collection1] main]
[junit4] 2> 61360 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001\instance-2-001\collection1\conf
[junit4] 2> 61362 INFO (coreLoadExecutor-80-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 61362 INFO (searcherExecutor-81-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@510807[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 61887 INFO (qtp25557248-209) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9]} 0 8
[junit4] 2> 61887 INFO (qtp25557248-209) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 61887 INFO (qtp25557248-209) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@b7dd11 commitCommandVersion:0
[junit4] 2> 62127 INFO (qtp25557248-209) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4df59f[collection1] main]
[junit4] 2> 62131 INFO (searcherExecutor-81-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4df59f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):C10:[diagnostics={os=Windows 10, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.0, timestamp=1557337793471}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_COMPRESSION}])))}
[junit4] 2> 62131 INFO (qtp25557248-209) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 62133 INFO (qtp25557248-209) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 242
[junit4] 2> 62133 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=4556961
[junit4] 2> 62143 INFO (coreCloseExecutor-85-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@a083ef
[junit4] 2> 62143 INFO (coreCloseExecutor-85-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=a083ef
[junit4] 2> 62143 INFO (coreCloseExecutor-85-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a21ed7: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@14bfe89
[junit4] 2> 62250 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 62250 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f8d049: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@f44b04
[junit4] 2> 62255 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 62255 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15e0a5a: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 62266 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 62266 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@87cc35: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 62276 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@91875b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 62276 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12852e1{/solr,null,UNAVAILABLE}
[junit4] 2> 62276 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 63308 INFO (qtp10173840-182) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 63342 INFO (qtp10173840-188) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=3
[junit4] 2> 63346 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=9191613
[junit4] 2> 63348 INFO (coreCloseExecutor-87-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@722b40
[junit4] 2> 63348 INFO (coreCloseExecutor-87-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=722b40
[junit4] 2> 63348 INFO (coreCloseExecutor-87-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e36866: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@cd94e0
[junit4] 2> 63472 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 63472 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15d572c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1967f69
[junit4] 2> 63478 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 63480 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ae655f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 63496 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 63496 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@42b2fe: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 63520 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@b703f9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 63520 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1ce09b4{/solr,null,UNAVAILABLE}
[junit4] 2> 63520 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 63524 WARN (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 63524 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=54232)
[junit4] 2> 63526 INFO (TEST-TestLBHttp2SolrClient.testTwoServers-seed#[3125228B40FDD0C]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 54232 try number 1 ...
[junit4] 2> 63526 INFO (TE
[...truncated too long message...]
for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@12f9fc5
[junit4] 2> 79237 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 79238 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13f150f: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1b764a1
[junit4] 2> 79241 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 79241 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@186f5b5: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 79248 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 79248 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1940e13: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 79257 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@f582d2{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 79257 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b57bc{/solr,null,UNAVAILABLE}
[junit4] 2> 79257 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 79265 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=12314272
[junit4] 2> 79272 INFO (coreCloseExecutor-162-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@fd79d1
[junit4] 2> 79272 INFO (coreCloseExecutor-162-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=fd79d1
[junit4] 2> 79272 INFO (coreCloseExecutor-162-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@113a20c: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1b7dd51
[junit4] 2> 79336 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 79336 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14c09d1: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@12809ac
[junit4] 2> 79341 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 79341 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1da6835: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 79352 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 79352 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@18672a3: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 79374 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1c3d483{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:54348}
[junit4] 2> 79374 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1aced0d{/solr,null,UNAVAILABLE}
[junit4] 2> 79374 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 79392 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=32878853
[junit4] 2> 79398 INFO (coreCloseExecutor-164-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c4464d
[junit4] 2> 79398 INFO (coreCloseExecutor-164-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=c4464d
[junit4] 2> 79398 INFO (coreCloseExecutor-164-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@26d7e6: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6b8522
[junit4] 2> 79419 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 79419 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21a3ec: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@43148
[junit4] 2> 79420 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 79421 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b38d8f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@193755c
[junit4] 2> 79421 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 79421 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@179632f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@23028a
[junit4] 2> 79428 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1d90ddc{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 79428 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c0b10a{/solr,null,UNAVAILABLE}
[junit4] 2> 79428 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 79444 INFO (TEST-TestLBHttp2SolrClient.testSimple-seed#[3125228B40FDD0C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSimple
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.TestLBHttp2SolrClient_3125228B40FDD0C-001
[junit4] 2> NOTE: test params are: codec=Lucene80, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@8181a4), locale=es-HN, timezone=Europe/Lisbon
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_201 (32-bit)/cpus=3,threads=1,free=17103592,total=65011712
[junit4] 2> NOTE: All tests run in this JVM: [RegressionEvaluatorTest, FieldAnalysisResponseTest, CommonParamsTest, TestLBHttp2SolrClient]
[junit4] Completed [22/193 (1!)] on J1 in 65.45s, 3 tests, 1 error <<< FAILURES!
[...truncated 45358 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/243/consoleText
[repro] Revision: 212b2b97dc8c17658b046510ca925083be9f60a1
[repro] Ant options: "-Dargs=-server -XX:+UseSerialGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\solrj
[repro] TestLBHttp2SolrClient
[repro] ant compile-test
[...truncated 2733 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestLBHttp2SolrClient" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseSerialGC" -Dtests.seed=3125228B40FDD0C -Dtests.slow=true -Dtests.locale=es-HN -Dtests.timezone=Europe/Lisbon -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 78 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.TestLBHttp2SolrClient
[repro] Exiting with code 0
[...truncated 78 lines...]