You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2019/05/18 05:37:00 UTC
[JENKINS] Lucene-Solr-BadApples-Tests-8.x - Build # 104 - Still
Failing
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-8.x/104/
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 4 object(s) that were not released!!! [SolrCore, MockDirectoryWrapper, InternalHttpClient, MockDirectoryWrapper] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1063) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348) at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:517) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:968) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321) at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330) at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:230) at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:272) at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1224) at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1009) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:99) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:779) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:976) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) expected null, but was:<ObjectTracker found 4 object(s) that were not released!!! [SolrCore, MockDirectoryWrapper, InternalHttpClient, MockDirectoryWrapper] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1063) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348) at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:517) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:968) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321) at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330) at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:230) at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:272) at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1224) at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1009) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348) at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:99) at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:779) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:976) at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883) at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238) at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749) at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) >
Stack Trace:
java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [SolrCore, MockDirectoryWrapper, InternalHttpClient, MockDirectoryWrapper]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1063)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:517)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:968)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)
at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)
at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:230)
at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:272)
at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1224)
at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1009)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:99)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:779)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:976)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
expected null, but was:<ObjectTracker found 4 object(s) that were not released!!! [SolrCore, MockDirectoryWrapper, InternalHttpClient, MockDirectoryWrapper]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1063)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:517)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:968)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)
at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)
at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:230)
at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:272)
at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1224)
at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1009)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:99)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:779)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:976)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
>
at __randomizedtesting.SeedInfo.seed([EA2B0C83FDDF2B9B]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotNull(Assert.java:755)
at org.junit.Assert.assertNull(Assert.java:737)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:333)
at sun.reflect.GeneratedMethodAccessor61.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:901)
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.metrics.rrd.SolrRrdBackendFactoryTest.testBasic
Error Message:
{} expected:<1> but was:<0>
Stack Trace:
java.lang.AssertionError: {} expected:<1> but was:<0>
at __randomizedtesting.SeedInfo.seed([EA2B0C83FDDF2B9B:41D111962203ADB5]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic(SolrRrdBackendFactoryTest.java:94)
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 13411 lines...]
[junit4] Suite: org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.metrics.rrd.SolrRrdBackendFactoryTest_EA2B0C83FDDF2B9B-001/init-core-data-001
[junit4] 2> 1067588 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1067589 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1067590 INFO (SUITE-SolrRrdBackendFactoryTest-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1067624 INFO (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasic
[junit4] 2> 1067624 DEBUG (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrRrdBackendFactory Created 1766526979
[junit4] 2> 1067676 DEBUG (SolrRrdBackendFactory-1964-thread-1) [ ] o.a.s.m.r.SolrRrdBackendFactory -- maybe sync backends: [foo]
[junit4] 2> 1067677 DEBUG (SolrRrdBackendFactory-1964-thread-1) [ ] o.a.s.m.r.SolrRrdBackendFactory -- syncing [foo]
[junit4] 2> 1067713 DEBUG (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrRrdBackendFactory Closing 1766526979
[junit4] 2> 1067713 INFO (TEST-SolrRrdBackendFactoryTest.testBasic-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBasic
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrRrdBackendFactoryTest -Dtests.method=testBasic -Dtests.seed=EA2B0C83FDDF2B9B -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=vi -Dtests.timezone=Asia/Vientiane -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.23s J0 | SolrRrdBackendFactoryTest.testBasic <<<
[junit4] > Throwable #1: java.lang.AssertionError: {} expected:<1> but was:<0>
[junit4] > at __randomizedtesting.SeedInfo.seed([EA2B0C83FDDF2B9B:41D111962203ADB5]:0)
[junit4] > at org.apache.solr.metrics.rrd.SolrRrdBackendFactoryTest.testBasic(SolrRrdBackendFactoryTest.java:94)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.metrics.rrd.SolrRrdBackendFactoryTest_EA2B0C83FDDF2B9B-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=649, maxMBSortInHeap=7.581555475583958, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6aa5e08c), locale=vi, timezone=Asia/Vientiane
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=244186264,total=522715136
[junit4] 2> NOTE: All tests run in this JVM: [TestSimTriggerIntegration, TestSolrCoreParser, ZkControllerTest, IndexBasedSpellCheckerTest, TestCloudNestedDocsSort, TestFieldCacheVsDocValues, SolrLogAuditLoggerPluginTest, TestOnReconnectListenerSupport, TestJsonFacetsWithNestedObjects, PeerSyncReplicationTest, TestReRankQParserPlugin, DocumentAnalysisRequestHandlerTest, InfixSuggestersTest, HighlighterConfigTest, TestSweetSpotSimilarityFactory, NodeMutatorTest, SimplePostToolTest, TestEmbeddedSolrServerAdminHandler, UnloadDistributedZkTest, TestDynamicURP, HdfsNNFailoverTest, ZkStateWriterTest, CdcrBidirectionalTest, SolrCloudExampleTest, AsyncCallRequestStatusResponseTest, TermsComponentTest, StressHdfsTest, TestMacros, TestSolrCloudWithSecureImpersonation, TestSolrCoreSnapshots, TestMissingGroups, TestTrieFacet, HdfsAutoAddReplicasIntegrationTest, HdfsRestartWhileUpdatingTest, PathHierarchyTokenizerFactoryTest, TestXIncludeConfig, EchoParamsTest, MetricTriggerIntegrationTest, TestInitQParser, TestDocBasedVersionConstraints, DeleteLastCustomShardedReplicaTest, TestClusterProperties, CoreAdminHandlerTest, DistributedUpdateProcessorTest, UpdateParamsTest, DistanceFunctionTest, TestLRUStatsCache, TestRebalanceLeaders, CursorPagingTest, ReturnFieldsTest, ReplaceNodeTest, TestManagedSynonymGraphFilterFactory, TestStressLucene, SolrRrdBackendFactoryTest]
[junit4] Completed [158/858 (1!)] on J0 in 0.89s, 1 test, 1 failure <<< FAILURES!
[...truncated 533 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 1493540 INFO (SUITE-TestReplicationHandler-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/init-core-data-001
[junit4] 2> 1493541 WARN (SUITE-TestReplicationHandler-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27
[junit4] 2> 1493541 INFO (SUITE-TestReplicationHandler-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1493543 INFO (SUITE-TestReplicationHandler-seed#[EA2B0C83FDDF2B9B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1493557 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestHandlerPathUnchanged
[junit4] 2> 1493558 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/collection1
[junit4] 2> 1493614 WARN (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1493614 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1493614 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1493614 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 1493726 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1493726 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1493726 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1493758 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@369ed69b{/solr,null,AVAILABLE}
[junit4] 2> 1493759 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6cf0259b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36143}
[junit4] 2> 1493759 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server Started @1494008ms
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=36143}
[junit4] 2> 1493760 ERROR (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-18T03:46:16.378Z
[junit4] 2> 1493760 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/solr.xml
[junit4] 2> 1493796 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1493796 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1493798 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1494386 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1494388 WARN (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4e4a2321[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494415 WARN (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@75a7a578[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1494488 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1494488 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1494543 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1494576 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1494576 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1494586 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/.
[junit4] 2> 1494586 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1494610 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1494647 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1494661 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1494661 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/./collection1, trusted=true
[junit4] 2> 1494662 INFO (coreLoadExecutor-4026-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@6fd4489
[junit4] 2> 1494662 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1494663 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=18.646484375, floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=28.88866695006085, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=21.478214096865635
[junit4] 2> 1494915 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1494915 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1494915 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1494916 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6973e751[collection1] main]
[junit4] 2> 1494916 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-001/collection1/conf
[junit4] 2> 1494929 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1494929 INFO (coreLoadExecutor-4026-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1494931 INFO (searcherExecutor-4027-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6973e751[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1494944 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/collection1
[junit4] 2> 1494946 WARN (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1494947 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1494947 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1494947 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 1494962 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1494962 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1494962 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1494962 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6a3f38{/solr,null,AVAILABLE}
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6891b77b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40192}
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server Started @1495212ms
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=40192}
[junit4] 2> 1494963 ERROR (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-18T03:46:17.581Z
[junit4] 2> 1494963 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/solr.xml
[junit4] 2> 1494987 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1494987 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1494989 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1496510 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1496512 WARN (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3a3cfe2e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1496529 WARN (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ed6d857[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1496597 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1496597 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1496654 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1496700 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1496700 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1496702 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/.
[junit4] 2> 1496702 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1496732 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1496769 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1496771 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1496771 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/./collection1, trusted=true
[junit4] 2> 1496771 INFO (coreLoadExecutor-4039-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@6fd4489
[junit4] 2> 1496771 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1496797 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=18.646484375, floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=28.88866695006085, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=21.478214096865635
[junit4] 2> 1496959 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1496959 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1496960 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1496960 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@37adf74e[collection1] main]
[junit4] 2> 1496960 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-002/collection1/conf
[junit4] 2> 1496962 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1496962 INFO (coreLoadExecutor-4039-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1496963 INFO (searcherExecutor-4040-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37adf74e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1496975 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestHandlerPathUnchanged
[junit4] 2> 1496975 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1263779461
[junit4] 2> 1496976 INFO (coreCloseExecutor-4045-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3b025513
[junit4] 2> 1496976 INFO (coreCloseExecutor-4045-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=3b025513
[junit4] 2> 1496976 INFO (coreCloseExecutor-4045-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@628c9ec1: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@781091e2
[junit4] 2> 1496977 ERROR (qtp303486112-14357) [ ] o.a.s.s.SolrDispatchFilter Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> 1496977 WARN (qtp303486112-14357) [ ] o.e.j.s.HttpChannel /solr/collection1/replication
[junit4] 2> => javax.servlet.UnavailableException: Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
[junit4] 2> javax.servlet.UnavailableException: Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361) ~[java/:?]
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:343) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:165) ~[java/:?]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) ~[jetty-servlet-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:753) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) ~[jetty-server-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) ~[jetty-io-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) ~[jetty-io-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) ~[jetty-io-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) ~[jetty-util-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) ~[jetty-util-9.4.14.v20181114.jar:9.4.14.v20181114]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_191]
[junit4] 2> 1497004 WARN (indexFetcher-4044-thread-1) [ ] o.a.s.h.IndexFetcher Master at: http://127.0.0.1:36143/solr/collection1 is not available. Index fetch failed by exception: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:36143/solr/collection1: Expected mime type application/octet-stream but got text/html. <html>
[junit4] 2> <head>
[junit4] 2> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
[junit4] 2> <title>Error 404 Not Found</title>
[junit4] 2> </head>
[junit4] 2> <body><h2>HTTP ERROR 404</h2>
[junit4] 2> <p>Problem accessing /solr/collection1/replication. Reason:
[junit4] 2> <pre> Not Found</pre></p><h3>Caused by:</h3><pre>javax.servlet.UnavailableException: Error processing the request. CoreContainer is either not initialized or shutting down.
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:361)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:343)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:165)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:753)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> </pre>
[junit4] 2> <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.14.v20181114</a><hr/>
[junit4] 2>
[junit4] 2> </body>
[junit4] 2> </html>
[junit4] 2>
[junit4] 2> 1497010 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1497010 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@64de6715: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@3503fc6e
[junit4] 2> 1497029 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1497029 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73f3b1ec: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@937664
[junit4] 2> 1497043 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1497043 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10b65268: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2e46d142
[junit4] 2> 1497046 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6cf0259b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1497046 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@369ed69b{/solr,null,UNAVAILABLE}
[junit4] 2> 1497046 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1497047 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2144428188
[junit4] 2> 1497076 INFO (coreCloseExecutor-4047-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4fd45a2b
[junit4] 2> 1497076 INFO (coreCloseExecutor-4047-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4fd45a2b
[junit4] 2> 1497076 INFO (coreCloseExecutor-4047-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5245d5c0: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@23b4d05
[junit4] 2> 1497117 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1497117 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@796c55c6: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@455dd2c
[junit4] 2> 1497133 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1497133 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5473dac1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@937664
[junit4] 2> 1497136 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1497136 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@49a54d80: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2e46d142
[junit4] 2> 1497153 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6891b77b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1497153 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6a3f38{/solr,null,UNAVAILABLE}
[junit4] 2> 1497153 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1497205 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexAndConfigAliasReplication
[junit4] 2> 1497205 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/collection1
[junit4] 2> 1497208 WARN (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1497208 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1497208 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1497208 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 1497209 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1497209 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1497209 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1497246 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@312c3c4{/solr,null,AVAILABLE}
[junit4] 2> 1497246 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2fdd64c1{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:35380}
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server Started @1497496ms
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=35380}
[junit4] 2> 1497247 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-18T03:46:19.865Z
[junit4] 2> 1497247 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/solr.xml
[junit4] 2> 1497269 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1497269 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1497283 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1497745 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1497746 WARN (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@507ff1e8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1497761 WARN (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@537b6a53[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1497814 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1497814 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1497837 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1497856 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1497856 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1497866 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/.
[junit4] 2> 1497866 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1497894 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1497923 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1497925 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1497925 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/./collection1, trusted=true
[junit4] 2> 1497925 INFO (coreLoadExecutor-4057-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@6fd4489
[junit4] 2> 1497925 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/./collection1/data/]
[junit4] 2> 1497935 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=18.646484375, floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=28.88866695006085, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=21.478214096865635
[junit4] 2> 1498033 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1498033 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1498034 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1498034 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d72fa56[collection1] main]
[junit4] 2> 1498034 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-003/collection1/conf
[junit4] 2> 1498035 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1498035 INFO (coreLoadExecutor-4057-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1498035 INFO (searcherExecutor-4058-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d72fa56[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1498036 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-004/collection1
[junit4] 2> 1498039 WARN (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1498039 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1498039 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1498039 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 1498043 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1498043 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1498043 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1498043 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3d60b0a5{/solr,null,AVAILABLE}
[junit4] 2> 1498043 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5a55ad15{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46058}
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.s.Server Started @1498293ms
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=46058}
[junit4] 2> 1498044 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-18T03:46:20.662Z
[junit4] 2> 1498044 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_EA2B0C83FDDF2B9B-001/solr-instance-004/solr.xml
[junit4] 2> 1498047 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1498047 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1498069 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1498198 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1498199 WARN (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4c196973[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1498215 WARN (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@381a85bf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1498251 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1498251 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1498312 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1498350 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1498350 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[EA2B0C83FDDF2B9B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6fd4489
[junit4] 2> 1498352 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAli
[...truncated too long message...]
ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
[junit4] > at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:99)
[junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:779)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:976)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
[junit4] > at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
[junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > expected null, but was:<ObjectTracker found 4 object(s) that were not released!!! [SolrCore, MockDirectoryWrapper, InternalHttpClient, MockDirectoryWrapper]
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.solr.core.SolrCore
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1063)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
[junit4] > at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
[junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
[junit4] > at org.apache.solr.core.SolrCore.initSnapshotMetaDataManager(SolrCore.java:517)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:968)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
[junit4] > at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
[junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.http.impl.client.InternalHttpClient
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:321)
[junit4] > at org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:330)
[junit4] > at org.apache.solr.handler.IndexFetcher.createHttpClient(IndexFetcher.java:230)
[junit4] > at org.apache.solr.handler.IndexFetcher.<init>(IndexFetcher.java:272)
[junit4] > at org.apache.solr.handler.ReplicationHandler.inform(ReplicationHandler.java:1224)
[junit4] > at org.apache.solr.core.SolrResourceLoader.inform(SolrResourceLoader.java:696)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1009)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
[junit4] > at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
[junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException: org.apache.lucene.store.MockDirectoryWrapper
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:348)
[junit4] > at org.apache.solr.update.SolrIndexWriter.create(SolrIndexWriter.java:99)
[junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:779)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:976)
[junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:883)
[junit4] > at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1238)
[junit4] > at org.apache.solr.core.CoreContainer.lambda$load$13(CoreContainer.java:749)
[junit4] > at com.codahale.metrics.InstrumentedExecutorService$InstrumentedCallable.call(InstrumentedExecutorService.java:202)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > >
[junit4] > at __randomizedtesting.SeedInfo.seed([EA2B0C83FDDF2B9B]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:333)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [281/858 (2!)] on J1 in 274.87s, 20 tests, 1 failure <<< FAILURES!
[...truncated 51831 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[java] Processed 2531 links (2070 relative) to 3359 anchors in 253 files
[echo] Validated Links & Anchors via: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-ref-guide/bare-bones-html/
-documentation-lint:
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[echo] Checking for malformed docs...
[exec]
[exec] /home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/docs/solr-solrj/overview-summary.html
[exec] missing description: org.noggit
[exec]
[exec] Missing javadocs were found!
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.xml:643: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.xml:101: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:660: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:676: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/common-build.xml:2530: exec returned: 1
Total time: 224 minutes 21 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-BadApples-Tests-8.x - Build # 105 - Still
Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-BadApples-Tests-8.x/105/
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution
Error Message:
0.8270447626785014 0.8286288686448905
Stack Trace:
java.lang.AssertionError: 0.8270447626785014 0.8286288686448905
at __randomizedtesting.SeedInfo.seed([E3F8F49E99795575:DE82DF30BA01FF62]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution(MathExpressionTest.java:4590)
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 16653 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/init-core-data-001
[junit4] 2> 187581 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 187583 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 187583 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 187584 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001
[junit4] 2> 187585 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 187592 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 187592 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 187692 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34908
[junit4] 2> 187692 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34908
[junit4] 2> 187692 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34908
[junit4] 2> 187701 INFO (zkConnectionManagerCallback-925-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187714 INFO (zkConnectionManagerCallback-927-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187716 INFO (zkConnectionManagerCallback-929-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187726 WARN (jetty-launcher-930-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 187726 WARN (jetty-launcher-930-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 187726 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 187727 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 187727 INFO (jetty-launcher-930-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_191-b12
[junit4] 2> 187727 WARN (jetty-launcher-930-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 187727 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 187727 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 187727 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 187727 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 187730 INFO (jetty-launcher-930-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_191-b12
[junit4] 2> 187731 INFO (jetty-launcher-930-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_191-b12
[junit4] 2> 187739 WARN (jetty-launcher-930-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 187739 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 187739 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 187739 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12
[junit4] 2> 187742 INFO (jetty-launcher-930-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 187743 INFO (jetty-launcher-930-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 187743 INFO (jetty-launcher-930-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 187743 INFO (jetty-launcher-930-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@70f2da73{/solr,null,AVAILABLE}
[junit4] 2> 187746 WARN (jetty-launcher-930-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3d57f779[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187749 INFO (jetty-launcher-930-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 187749 INFO (jetty-launcher-930-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 187749 INFO (jetty-launcher-930-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 187749 INFO (jetty-launcher-930-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3a9acf34{/solr,null,AVAILABLE}
[junit4] 2> 187750 WARN (jetty-launcher-930-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5e51aba0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e158b6f{SSL,[ssl, http/1.1]}{127.0.0.1:39665}
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-3) [ ] o.e.j.s.Server Started @187827ms
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39665}
[junit4] 2> 187757 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10fc2a14{/solr,null,AVAILABLE}
[junit4] 2> 187758 ERROR (jetty-launcher-930-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 187758 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 187758 WARN (jetty-launcher-930-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5f359aed[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187758 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 187758 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 187758 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 187758 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-19T09:46:08.402Z
[junit4] 2> 187760 INFO (jetty-launcher-930-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c9b6f82{SSL,[ssl, http/1.1]}{127.0.0.1:45733}
[junit4] 2> 187760 INFO (jetty-launcher-930-thread-1) [ ] o.e.j.s.Server Started @187830ms
[junit4] 2> 187760 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45733}
[junit4] 2> 187760 ERROR (jetty-launcher-930-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 187760 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 187763 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 187763 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 187763 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 187763 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-19T09:46:08.407Z
[junit4] 2> 187765 INFO (jetty-launcher-930-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 187765 INFO (jetty-launcher-930-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 187765 INFO (jetty-launcher-930-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 187770 INFO (jetty-launcher-930-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@539f6b5{/solr,null,AVAILABLE}
[junit4] 2> 187770 WARN (jetty-launcher-930-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2fd86eb2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@43260df0{SSL,[ssl, http/1.1]}{127.0.0.1:35182}
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.e.j.s.Server Started @187843ms
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35182}
[junit4] 2> 187773 ERROR (jetty-launcher-930-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 187773 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 187774 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-19T09:46:08.417Z
[junit4] 2> 187777 INFO (zkConnectionManagerCallback-932-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@ac41960{SSL,[ssl, http/1.1]}{127.0.0.1:37266}
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.e.j.s.Server Started @187847ms
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37266}
[junit4] 2> 187777 ERROR (jetty-launcher-930-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.0
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 187777 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 187778 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-05-19T09:46:08.421Z
[junit4] 2> 187778 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 187778 INFO (zkConnectionManagerCallback-936-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187784 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 187785 INFO (zkConnectionManagerCallback-934-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187785 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 187786 INFO (zkConnectionManagerCallback-938-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187787 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 187931 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 187931 WARN (jetty-launcher-930-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 187964 WARN (jetty-launcher-930-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1d7b98fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187975 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 187975 WARN (jetty-launcher-930-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 187976 WARN (jetty-launcher-930-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 187976 WARN (jetty-launcher-930-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@42b69379[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187979 WARN (jetty-launcher-930-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 187980 WARN (jetty-launcher-930-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3498eb06[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187981 INFO (jetty-launcher-930-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
[junit4] 2> 187981 WARN (jetty-launcher-930-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@35410a6c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 187982 INFO (jetty-launcher-930-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
[junit4] 2> 187985 INFO (zkConnectionManagerCallback-954-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187991 INFO (zkConnectionManagerCallback-951-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 187992 INFO (zkConnectionManagerCallback-956-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188008 WARN (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 188008 INFO (zkConnectionManagerCallback-958-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188014 WARN (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 188016 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37266_solr
[junit4] 2> 188017 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.Overseer Overseer (id=73328593821040649-127.0.0.1:37266_solr-n_0000000000) starting
[junit4] 2> 188021 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39665_solr
[junit4] 2> 188025 INFO (zkCallback-957-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 188040 INFO (zkConnectionManagerCallback-967-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188041 INFO (zkConnectionManagerCallback-972-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188042 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 188043 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 188043 INFO (zkCallback-955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 188044 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
[junit4] 2> 188048 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
[junit4] 2> 188061 INFO (OverseerStateUpdate-73328593821040649-127.0.0.1:37266_solr-n_0000000000) [n:127.0.0.1:37266_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37266_solr
[junit4] 2> 188068 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37266_solr as DOWN
[junit4] 2> 188070 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 188070 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37266_solr
[junit4] 2> 188074 INFO (zkCallback-955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 188075 INFO (zkCallback-966-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 188085 INFO (zkCallback-971-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 188085 INFO (zkCallback-957-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 188097 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 188115 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 188170 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37266.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188171 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39665.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188199 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39665.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188200 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39665.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188201 INFO (jetty-launcher-930-thread-3) [n:127.0.0.1:39665_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node2/.
[junit4] 2> 188224 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37266.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188228 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37266.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188230 INFO (jetty-launcher-930-thread-2) [n:127.0.0.1:37266_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node3/.
[junit4] 2> 188264 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 188265 WARN (jetty-launcher-930-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 188266 WARN (jetty-launcher-930-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1aaf300c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 188269 WARN (jetty-launcher-930-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 188270 WARN (jetty-launcher-930-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@49d4a1cb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 188271 INFO (jetty-launcher-930-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
[junit4] 2> 188284 INFO (zkConnectionManagerCallback-980-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188305 INFO (zkConnectionManagerCallback-982-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188314 WARN (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 188316 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 188328 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:35182_solr as DOWN
[junit4] 2> 188330 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 188330 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35182_solr
[junit4] 2> 188332 INFO (zkCallback-957-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 188332 INFO (zkCallback-971-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 188332 INFO (zkCallback-955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 188332 INFO (zkCallback-966-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 188336 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 188337 INFO (zkConnectionManagerCallback-989-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188339 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 188346 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
[junit4] 2> 188374 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 188398 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35182.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188412 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35182.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188412 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35182.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188416 INFO (jetty-launcher-930-thread-4) [n:127.0.0.1:35182_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node4/.
[junit4] 2> 188587 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 188587 WARN (jetty-launcher-930-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 188590 WARN (jetty-launcher-930-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@705b6dd1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 188595 WARN (jetty-launcher-930-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 188596 WARN (jetty-launcher-930-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3c8a09a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 188597 INFO (jetty-launcher-930-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34908/solr
[junit4] 2> 188600 INFO (zkConnectionManagerCallback-996-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188605 INFO (zkConnectionManagerCallback-998-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188611 WARN (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 188612 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 188617 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:45733_solr as DOWN
[junit4] 2> 188620 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 188620 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45733_solr
[junit4] 2> 188622 INFO (zkCallback-955-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188622 INFO (zkCallback-957-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188623 INFO (zkCallback-971-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188623 INFO (zkCallback-966-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188623 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188626 INFO (zkConnectionManagerCallback-1005-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188627 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 188628 INFO (zkCallback-997-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188628 INFO (zkCallback-988-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 188629 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
[junit4] 2> 188659 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 188683 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45733.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188697 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45733.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188697 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45733.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 188698 INFO (jetty-launcher-930-thread-1) [n:127.0.0.1:45733_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node1/.
[junit4] 2> 188769 INFO (zkConnectionManagerCallback-1011-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 188770 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 188774 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
[junit4] 2> 188802 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 188808 INFO (OverseerThreadFactory-548-thread-1-processing-n:127.0.0.1:37266_solr) [n:127.0.0.1:37266_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 188925 INFO (OverseerStateUpdate-73328593821040649-127.0.0.1:37266_solr-n_0000000000) [n:127.0.0.1:37266_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45733/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 188935 INFO (OverseerStateUpdate-73328593821040649-127.0.0.1:37266_solr-n_0000000000) [n:127.0.0.1:37266_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:35182/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 189180 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr x:collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 189191 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr x:collection1_shard2_replica_n3] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 190219 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 190220 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 190240 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 190240 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.IndexSchema [collection1_shard2_replica_n3] Schema name=test
[junit4] 2> 190350 WARN (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 190350 WARN (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 190351 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 190351 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 190352 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45733.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 190352 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/]
[junit4] 2> 190409 WARN (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 190409 WARN (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 190410 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 190410 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n3' using configuration from collection collection1, trusted=true
[junit4] 2> 190410 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35182.solr.core.collection1.shard2.replica_n3' (registry 'solr.core.collection1.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@34d70843
[junit4] 2> 190411 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SolrCore [[collection1_shard2_replica_n3] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node4/collection1_shard2_replica_n3], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001/tempDir-001/node4/./collection1_shard2_replica_n3/data/]
[junit4] 2> 190438 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 190438 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 190439 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 190439 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 190441 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7839d4d1[collection1_shard1_replica_n1] main]
[junit4] 2> 190446 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 190447 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 190447 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 190448 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633953168586964992
[junit4] 2> 190462 INFO (searcherExecutor-570-thread-1-processing-n:127.0.0.1:45733_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@7839d4d1[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 190476 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 190476 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 190480 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 190480 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 190480 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/
[junit4] 2> 190482 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 190482 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 190482 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/73328593821040657-core_node2-n_0000000000
[junit4] 2> 190486 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 190524 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 190524 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 190526 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 190526 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 190531 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@11e5f89[collection1_shard2_replica_n3] main]
[junit4] 2> 190534 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 190534 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 190535 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 190535 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1633953168678191104
[junit4] 2> 190541 INFO (searcherExecutor-571-thread-1-processing-n:127.0.0.1:35182_solr x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SolrCore [collection1_shard2_replica_n3] Registered new searcher Searcher@11e5f89[collection1_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 190547 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 190547 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 190552 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 190553 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 190553 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/
[junit4] 2> 190563 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 190564 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SyncStrategy https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/ has no replicas
[junit4] 2> 190564 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/73328593821040654-core_node4-n_0000000000
[junit4] 2> 190567 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/ shard2
[junit4] 2> 190670 INFO (zkCallback-997-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190670 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190672 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 190672 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 190677 INFO (zkCallback-997-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190677 INFO (zkCallback-981-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190678 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1487
[junit4] 2> 190680 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1500
[junit4] 2> 190693 INFO (qtp715068116-2649) [n:127.0.0.1:35182_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> 190789 INFO (zkCallback-997-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190789 INFO (zkCallback-997-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190789 INFO (zkCallback-981-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190789 INFO (zkCallback-981-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190791 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1989
[junit4] 2> 190792 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):45
[junit4] 2> 190800 INFO (zkCallback-997-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190808 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 190829 INFO (zkCallback-981-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 190888 INFO (OverseerCollectionConfigSetProcessor-73328593821040649-127.0.0.1:37266_solr-n_0000000000) [n:127.0.0.1:37266_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 191024 INFO (TEST-MathExpressionTest.testDescribe-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDescribe
[junit4] 2> 191191 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 191192 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1633953169362911232)} 0 3
[junit4] 2> 191196 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953169371299840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 191197 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 191199 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 191199 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3
[junit4] 2> 191202 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953169377591296,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 191202 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 191213 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 191213 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 191214 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1633953169207721984),commit=} 0 177
[junit4] 2> 191260 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[1 (1633953169434214400), 4 (1633953169438408704)]} 0 4
[junit4] 2> 191267 INFO (qtp715068116-2648) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[2 (1633953169434214400), 3 (1633953169444700160), 5 (1633953169444700161), 6 (1633953169444700162), 7 (1633953169445748736)]} 0 12
[junit4] 2> 191453 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953169640783872,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 191453 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@b08c336 commitCommandVersion:1633953169640783872
[junit4] 2> 191465 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@53e3c362[collection1_shard1_replica_n1] main]
[junit4] 2> 191470 INFO (searcherExecutor-570-thread-1-processing-n:127.0.0.1:45733_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@53e3c362[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.2.0):c2:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1558259172106}]))}
[junit4] 2> 191471 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 191471 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 18
[junit4] 2> 191480 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953169669095424,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 191497 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1482626e commitCommandVersion:1633953169669095424
[junit4] 2> 191510 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ac8ca71[collection1_shard2_replica_n3] main]
[junit4] 2> 191510 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 191512 INFO (searcherExecutor-571-thread-1-processing-n:127.0.0.1:35182_solr x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SolrCore [collection1_shard2_replica_n3] Registered new searcher Searcher@2ac8ca71[collection1_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(8.2.0):c5:[diagnostics={os=Linux, java.vendor=Oracle Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, lucene.version=8.2.0, os.arch=amd64, java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-137-generic, timestamp=1558259172150}]))}
[junit4] 2> 191513 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{commit=} 0 244
[junit4] 2> 191582 INFO (qtp1701003413-2629) [n:127.0.0.1:39665_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 191597 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/stream params={indent=off&expr=let(a%3Dsearch(collection1,+q%3D"col_s:a",+fl%3D"price_f,+order_i",+sort%3D"order_i+asc"),+b%3Dcol(a,+price_f),++stats%3Ddescribe(b))&wt=json&version=2.2} status=0 QTime=8
[junit4] 2> 191602 INFO (zkConnectionManagerCallback-1018-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 191605 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 191609 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34908/solr ready
[junit4] 2> 191786 INFO (qtp715068116-2648) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&rows=10&version=2&q=col_s:a&NOW=1558259172261&isShard=true&wt=javabin} hits=5 status=0 QTime=0
[junit4] 2> 191788 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={df=text&distrib=false&_stateVer_=collection1:4&fl=id&shards.purpose=4&start=0&fsv=true&sort=order_i+asc&shard.url=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&rows=10&version=2&q=col_s:a&NOW=1558259172261&isShard=true&wt=javabin} hits=2 status=0 QTime=7
[junit4] 2> 191854 INFO (qtp715068116-2646) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/select params={q=col_s:a&df=text&distrib=false&_stateVer_=collection1:4&fl=price_f,+order_i&fl=id&shards.purpose=64&NOW=1558259172261&ids=2,3,5,6,7&isShard=true&shard.url=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 191869 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=col_s:a&df=text&distrib=false&_stateVer_=collection1:4&fl=price_f,+order_i&fl=id&shards.purpose=64&NOW=1558259172261&ids=1,4&isShard=true&shard.url=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 191885 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/select params={q=col_s:a&_stateVer_=collection1:4&fl=price_f,+order_i&sort=order_i+asc&wt=javabin&version=2} hits=7 status=0 QTime=268
[junit4] 2> 191902 INFO (TEST-MathExpressionTest.testDescribe-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDescribe
[junit4] 2> 192027 INFO (TEST-MathExpressionTest.testChiSquareDataSet-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testChiSquareDataSet
[junit4] 2> 192033 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@90a778c[collection1_shard2_replica_n3] realtime]
[junit4] 2> 192036 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a3e883e[collection1_shard1_replica_n1] realtime]
[junit4] 2> 192036 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1633953170250006528)} 0 2
[junit4] 2> 192047 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953170263638016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 192049 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@b08c336 commitCommandVersion:1633953170263638016
[junit4] 2> 192050 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17ce10ba[collection1_shard1_replica_n1] main]
[junit4] 2> 192050 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 192052 INFO (searcherExecutor-570-thread-1-processing-n:127.0.0.1:45733_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@17ce10ba[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 192052 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6
[junit4] 2> 192064 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953170281463808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 192064 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1482626e commitCommandVersion:1633953170281463808
[junit4] 2> 192064 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d9a100d[collection1_shard2_replica_n3] main]
[junit4] 2> 192065 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 192066 INFO (searcherExecutor-571-thread-1-processing-n:127.0.0.1:35182_solr x:collection1_shard2_replica_n3 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.SolrCore [collection1_shard2_replica_n3] Registered new searcher Searcher@2d9a100d[collection1_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 192067 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1633953170244763648),commit=} 0 38
[junit4] 2> 192084 INFO (qtp715068116-2648) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,++++a%3Darray(1,1,2,3,4,5,6,7,9,10,11,12),+++++b%3Darray(1,1,2,3,4,5,6,7,1,1,1,1),+++++chisquare%3DchiSquareDataSet(a,+b))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 192098 INFO (TEST-MathExpressionTest.testChiSquareDataSet-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testChiSquareDataSet
[junit4] 2> 192227 INFO (TEST-MathExpressionTest.testNatural-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNatural
[junit4] 2> 192231 INFO (qtp487406579-2641) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1633953170455527424)} 0 1
[junit4] 2> 192252 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953170478596096,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 192252 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 192252 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 192252 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 192257 INFO (qtp715068116-2646) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953170483838976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 192257 INFO (qtp715068116-2646) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 192257 INFO (qtp715068116-2646) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 192257 INFO (qtp715068116-2646) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1633953170454478848),commit=} 0 28
[junit4] 2> 192269 INFO (qtp487406579-2640) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/stream params={indent=off&expr=natural(6)&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 192272 INFO (TEST-MathExpressionTest.testNatural-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNatural
[junit4] 2> 192385 INFO (TEST-MathExpressionTest.testGammaDistribution-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGammaDistribution
[junit4] 2> 192391 INFO (qtp487406579-2641) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1633953170622251008)} 0 2
[junit4] 2> 192410 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953170644271104,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 192410 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 192410 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 192410 INFO (qtp487406579-2637) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 192411 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953170645319680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 192411 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 192412 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 192412 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1633953170619105280),commit=} 0 26
[junit4] 2> 192421 INFO (qtp715068116-2650) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/stream params={indent=off&expr=#comment%0alet(echo%3Dtrue,+a%3Ddescribe(sample(gammaDistribution(1,+10),10000)),+%0a#+commment%0ab%3Ddescribe(sample(gammaDistribution(3,+10),10000)),+c%3Ddescribe(sample(gammaDistribution(5,+10),10000)),d%3Ddescribe(sample(gammaDistribution(7,+10),10000)),e%3Dmean(sample(gammaDistribution(1,+10),10000)),f%3Dmean(sample(gammaDistribution(1,+20),10000)),g%3Dmean(sample(gammaDistribution(1,+30),10000)))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 193039 INFO (TEST-MathExpressionTest.testGammaDistribution-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGammaDistribution
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MathExpressionTest -Dtests.method=testGammaDistribution -Dtests.seed=E3F8F49E99795575 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=cs -Dtests.timezone=Pacific/Pitcairn -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.78s J0 | MathExpressionTest.testGammaDistribution <<<
[junit4] > Throwable #1: java.lang.AssertionError: 0.8270447626785014 0.8286288686448905
[junit4] > at __randomizedtesting.SeedInfo.seed([E3F8F49E99795575:DE82DF30BA01FF62]:0)
[junit4] > at org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGammaDistribution(MathExpressionTest.java:4590)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 193124 INFO (TEST-MathExpressionTest.testSumSq-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSumSq
[junit4] 2> 193128 INFO (qtp487406579-2638) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&wt=javabin&version=2}{deleteByQuery=*:* (-1633953171396100096)} 0 1
[junit4] 2> 193131 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953171400294400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 193131 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 193131 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 193132 INFO (qtp487406579-2639) [n:127.0.0.1:45733_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:35182/solr/collection1_shard2_replica_n3/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 193133 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953171402391552,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 193133 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 193133 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 193133 INFO (qtp715068116-2647) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1633953171395051520),commit=} 0 7
[junit4] 2> 193140 INFO (qtp715068116-2648) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.c.S.Request [collection1_shard2_replica_n3] webapp=/solr path=/stream params={indent=off&expr=sumSq(array(-3,-2.5,+10))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 193142 INFO (TEST-MathExpressionTest.testSumSq-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSumSq
[junit4] 2> 193228 INFO (TEST-MathExpressionTest.testScale-seed#[E3F8F49E99795575]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScale
[junit4] 2> 193232 INFO (qtp715068116-2646) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n3] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45733/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1633953171505152000)} 0 1
[junit4] 2> 193238 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1633953171512492032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 193238 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 193239 INFO (qtp715068116-2649) [n:127.0.0.1:35182_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n3] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4]
[...truncated too long message...]
] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 220772 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 220773 INFO (jetty-closer-1026-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@201cf1f: rootName = solr_45733, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@56676757
[junit4] 2> 220773 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@780e8123: rootName = solr_35182, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6a742906
[junit4] 2> 220781 INFO (jetty-closer-1026-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 220781 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 220781 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2fb0556: rootName = solr_35182, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5617ac2f
[junit4] 2> 220781 INFO (jetty-closer-1026-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@41c577fa: rootName = solr_45733, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5617ac2f
[junit4] 2> 220784 INFO (jetty-closer-1026-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 220784 INFO (jetty-closer-1026-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d5e08f9: rootName = solr_45733, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@119f175d
[junit4] 2> 220785 INFO (jetty-closer-1026-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 220787 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 220787 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1f95795c: rootName = solr_35182, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@119f175d
[junit4] 2> 220787 INFO (jetty-closer-1026-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 220800 INFO (zkCallback-988-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 220801 INFO (zkCallback-988-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 220809 INFO (jetty-closer-1026-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1c9b6f82{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 220814 INFO (jetty-closer-1026-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3a9acf34{/solr,null,UNAVAILABLE}
[junit4] 2> 220815 INFO (jetty-closer-1026-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 220820 INFO (jetty-closer-1026-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@43260df0{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 220828 INFO (jetty-closer-1026-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10fc2a14{/solr,null,UNAVAILABLE}
[junit4] 2> 220828 INFO (jetty-closer-1026-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 220831 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 220838 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> 10 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/collections/collection1/state.json
[junit4] 2> 10 /solr/clusterstate.json
[junit4] 2> 10 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/live_nodes
[junit4] 2> 10 /solr/collections
[junit4] 2>
[junit4] 2> 220843 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34908
[junit4] 2> 220843 INFO (SUITE-MathExpressionTest-seed#[E3F8F49E99795575]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34908
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_E3F8F49E99795575-001
[junit4] 2> May 19, 2019 9:46:41 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=10, maxDocsPerChunk=180, blockSize=1), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=10, blockSize=1)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@49e86d7e), locale=cs, timezone=Pacific/Pitcairn
[junit4] 2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=4,threads=1,free=131541400,total=407371776
[junit4] 2> NOTE: All tests run in this JVM: [RegressionEvaluatorTest, TestValidatingJsonMap, HeatmapFacetMapTest, CommonParamsTest, CloudHttp2SolrClientMultiConstructorTest, NormalizeEvaluatorTest, ConcurrentUpdateHttp2SolrClientBadInputTest, ExclusiveOrEvaluatorTest, SolrExceptionTest, SolrExampleXMLHttp2Test, SolrPingTest, SolrExampleEmbeddedTest, CloudSolrClientBadInputTest, HyperbolicSineEvaluatorTest, DocumentAnalysisResponseTest, JDBCStreamTest, DivideEvaluatorTest, TestObjectBuilder, TestFastWriter, TangentEvaluatorTest, ArcCosineEvaluatorTest, PowerEvaluatorTest, HttpClientUtilTest, ConcatOperationTest, TestJsonRecordReader, CosineEvaluatorTest, JsonRequestApiHeatmapFacetingTest, JsonQueryRequestIntegrationTest, LargeVolumeEmbeddedTest, LBHttpSolrClientBuilderTest, SolrParamTest, GraphTest, JsonRequestApiTest, UsingSolrJRefGuideExamplesTest, ZkConfigFilesTest, CollectionAdminRequestRequiredParamsTest, GetByIdTest, SolrExampleBinaryHttp2Test, SolrQueryTest, SolrSchemalessExampleTest, TestBatchUpdate, TestSolrJErrorHandling, SolrExampleStreamingHttp2Test, BasicHttpSolrClientTest, CloudSolrClientCacheTest, ConcurrentUpdateHttp2SolrClientTest, HttpSolrClientBadInputTest, HttpSolrClientBuilderTest, HttpSolrClientConPoolTest, HttpSolrClientSSLAuthConPoolTest, TestCloudSolrClientConnections, MathExpressionTest]
[junit4] Completed [124/196 (1!)] on J0 in 33.61s, 119 tests, 1 failure <<< FAILURES!
[...truncated 49053 lines...]
[asciidoctor:convert] asciidoctor: ERROR: about-this-guide.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[asciidoctor:convert] asciidoctor: ERROR: solr-glossary.adoc: line 1: invalid part, must have at least one section (e.g., chapter, appendix, etc.)
[java] Processed 2531 links (2070 relative) to 3359 anchors in 253 files
[echo] Validated Links & Anchors via: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-ref-guide/bare-bones-html/
-documentation-lint:
[jtidy] Checking for broken html (such as invalid tags)...
[delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/build/jtidy_tmp
[echo] Checking for broken links...
[exec]
[exec] Crawl/parse...
[exec]
[exec] Verify...
[echo] Checking for malformed docs...
[exec]
[exec] /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build/docs/solr-solrj/overview-summary.html
[exec] missing description: org.noggit
[exec]
[exec] Missing javadocs were found!
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.xml:643: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/build.xml:101: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:660: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/solr/build.xml:676: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-Tests-8.x/lucene/common-build.xml:2530: exec returned: 1
Total time: 111 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any