[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build # 6157 - Still Unstable!

Policeman Jenkins Server Tue, 04 Oct 2016 05:46:46 -0700

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6157/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseSerialGC


4 tests failed.
FAILED:  org.apache.solr.TestDistributedSearch.test

Error Message:
Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: No live SolrServers 
available to handle this 
request,trace=org.apache.solr.client.solrj.SolrServerException: No live 
SolrServers available to handle this request  at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
  at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:226)
  at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:198)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
  at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
 at java.lang.Thread.run(Thread.java:745) ,time=2}

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: No live SolrServers 
available to handle this 
request,trace=org.apache.solr.client.solrj.SolrServerException: No live 
SolrServers available to handle this request
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:226)
        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:198)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
,time=2}
        at 
__randomizedtesting.SeedInfo.seed([2F64BCE0B8DAF64D:A730833A16269BB5]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1172)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1113)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:973)
        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:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        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:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        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:367)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.handler.TestSQLHandler.doTest

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([2F64BCE0B8DAF64D:88200444D561E5F4]:0)
        at 
org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:298)
        at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:91)
        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:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        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:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        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:367)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
junit.framework.TestSuite.org.apache.solr.client.solrj.TestSolrJErrorHandling

Error Message:
ObjectTracker found 1 object(s) that were not released!!! [InternalHttpClient] 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException  at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
  at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:262)
  at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:209)
  at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:201)
  at 
org.apache.solr.client.solrj.impl.CloudSolrClient.<init>(CloudSolrClient.java:393)
  at 
org.apache.solr.client.solrj.impl.CloudSolrClient.<init>(CloudSolrClient.java:100)
  at 
org.apache.solr.client.solrj.impl.CloudSolrClient$Builder.build(CloudSolrClient.java:1673)
  at 
org.apache.solr.client.solrj.impl.CloudSolrClientBuilderTest.testNoZkHostSpecified(CloudSolrClientBuilderTest.java:36)
  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:1764)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$11.evaluate(RandomizedRunner.java:968)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
  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:367)
  at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
  at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
  at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
  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:367)
  at java.lang.Thread.run(Thread.java:745)  

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not 
released!!! [InternalHttpClient]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
        at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:262)
        at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:209)
        at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:201)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.<init>(CloudSolrClient.java:393)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.<init>(CloudSolrClient.java:100)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient$Builder.build(CloudSolrClient.java:1673)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClientBuilderTest.testNoZkHostSpecified(CloudSolrClientBuilderTest.java:36)
        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:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$11.evaluate(RandomizedRunner.java:968)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        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:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        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:367)
        at java.lang.Thread.run(Thread.java:745)


        at __randomizedtesting.SeedInfo.seed([58B0E8C6C0D5419D]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
        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:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
        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:367)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  
junit.framework.TestSuite.org.apache.solr.client.solrj.impl.CloudSolrClientBuilderTest

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.client.solrj.impl.CloudSolrClientBuilderTest:     1) 
Thread[id=220, name=Connection evictor, state=TIMED_WAITING, 
group=TGRP-CloudSolrClientBuilderTest]         at java.lang.Thread.sleep(Native 
Method)         at 
org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.client.solrj.impl.CloudSolrClientBuilderTest: 
   1) Thread[id=220, name=Connection evictor, state=TIMED_WAITING, 
group=TGRP-CloudSolrClientBuilderTest]
        at java.lang.Thread.sleep(Native Method)
        at 
org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([58B0E8C6C0D5419D]:0)




Build Log:
[...truncated 10897 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\init-core-data-001
   [junit4]   2> 588449 INFO  
(SUITE-TestDistributedSearch-seed#[2F64BCE0B8DAF64D]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061)
   [junit4]   2> 588450 INFO  
(SUITE-TestDistributedSearch-seed#[2F64BCE0B8DAF64D]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 589178 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\control\cores\collection1
   [junit4]   2> 589182 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 589184 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6cf800ec{/,null,AVAILABLE}
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@48fbef1c{HTTP/1.1,[http/1.1]}{127.0.0.1:64108}
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] o.e.j.s.Server 
Started @595066ms
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=64108, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\control\cores}
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 589187 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-04T11:40:09.849Z
   [junit4]   2> 589189 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\control\solr.xml
   [junit4]   2> 589245 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\control\cores
   [junit4]   2> 589245 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 589252 INFO  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 589377 WARN  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 589380 INFO  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 589435 INFO  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 589584 WARN  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 589587 INFO  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 589614 INFO  (coreLoadExecutor-1813-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 589615 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 589615 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1197df26
   [junit4]   2> 589617 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 589642 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 589642 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 589643 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 589643 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 589644 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 589645 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2a66534a[collection1] main]
   [junit4]   2> 589645 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 589680 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 589774 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 589774 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 589775 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 589775 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 589775 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 589776 INFO  (coreLoadExecutor-1813-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1547259043316236288
   [junit4]   2> 589778 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 589779 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 589780 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 589780 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 589781 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 589781 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2a66534a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 590948 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 590951 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 590952 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@489aa8f0{/,null,AVAILABLE}
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@404211f9{HTTP/1.1,[http/1.1]}{127.0.0.1:64114}
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] o.e.j.s.Server 
Started @596833ms
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=64114, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\shard0\cores}
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 590954 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-04T11:40:11.616Z
   [junit4]   2> 590955 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 590981 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\shard0\cores
   [junit4]   2> 590981 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 590984 INFO  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 591061 WARN  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 591062 INFO  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 591084 INFO  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 591207 WARN  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 591210 INFO  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 591254 INFO  (coreLoadExecutor-1824-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 591254 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_2F64BCE0B8DAF64D-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 591254 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1197df26
   [junit4]   2> 591268 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 591299 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 591299 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 591300 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 591301 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 591303 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 591303 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@653e4685[collection1] main]
   [junit4]   2> 591308 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 591326 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 591379 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 591379 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 591381 INFO  (coreLoadExecutor-1824-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1547259044999200768
   [junit4]   2> 591381 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 591381 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 591381 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 591381 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 591381 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 591382 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 591383 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 591383 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 591384 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@653e4685[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 591485 INFO  
(TEST-TestDistributedSearch.test-seed#[2F64BCE0B8DAF64D]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 591492 INFO  (qtp1069764706-4163) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1547259045112446976)} 0 3
   [junit4]   2> 591500 INFO  (qtp199253719-4179) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1547259045117689856)} 0 5
   [junit4]   2> 591515 INFO  (qtp1069764706-4166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1547259045125029888)]} 0 13
   [junit4]   2> 591522 INFO  (qtp199253719-4179) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1547259045139709952)]} 0 6
   [junit4]   2> 591541 INFO  (qtp1069764706-4165) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1547259045149147136)]} 0 17
   [junit4]   2> 591552 INFO  (qtp199253719-4186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1547259045168021504)]} 0 10
   [junit4]   2> 591560 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1547259045179555840)]} 0 7
   [junit4]   2> 591565 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1547259045188993024)]} 0 3
   [junit4]   2> 591572 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1547259045193187328)]} 0 6
   [junit4]   2> 591573 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1547259045200527360)]} 0 0
   [junit4]   2> 591575 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1547259045201575936)]} 0 0
   [junit4]   2> 591576 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1547259045203673088)]} 0 0
   [junit4]   2> 591576 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1547259045203673088)]} 0 0
   [junit4]   2> 591578 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1547259045204721664)]} 0 1
   [junit4]   2> 591582 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1547259045206818816)]} 0 3
   [junit4]   2> 591584 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1547259045212061696)]} 0 0
   [junit4]   2> 591586 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1547259045212061696)]} 0 1
   [junit4]   2> 591587 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1547259045214158848)]} 0 0
   [junit4]   2> 591588 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1547259045215207424)]} 0 0
   [junit4]   2> 591589 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1547259045216256000)]} 0 0
   [junit4]   2> 591589 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 591589 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6008d104
   [junit4]   2> 591680 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3854796c[collection1] main]
   [junit4]   2> 591680 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 591681 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3854796c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 591681 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 91
   [junit4]   2> 591682 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 591682 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@491b8538
   [junit4]   2> 591735 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2ea11ed6[collection1] main]
   [junit4]   2> 591738 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2ea11ed6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 591738 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 591738 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 56
   [junit4]   2> 591743 INFO  (qtp1069764706-4168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1547259045375639552)]} 0 3
   [junit4]   2> 591747 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1547259045379833856)]} 0 2
   [junit4]   2> 591755 INFO  (qtp1069764706-4162) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1547259045382979584)]} 0 7
   [junit4]   2> 591757 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1547259045392416768)]} 0 0
   [junit4]   2> 591757 INFO  (qtp1069764706-4162) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1547259045393465344)]} 0 0
   [junit4]   2> 591760 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1547259045394513920)]} 0 2
   [junit4]   2> 591761 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1547259045397659648)]} 0 0
   [junit4]   2> 591762 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1547259045398708224)]} 0 0
   [junit4]   2> 591767 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1547259045400805376)]} 0 3
   [junit4]   2> 591768 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1547259045404999680)]} 0 0
   [junit4]   2> 591769 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1547259045406048256)]} 0 0
   [junit4]   2> 591772 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1547259045408145408)]} 0 0
   [junit4]   2> 591772 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1547259045409193984)]} 0 0
   [junit4]   2> 591773 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1547259045410242560)]} 0 0
   [junit4]   2> 591780 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1547259045411291136)]} 0 5
   [junit4]   2> 591781 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1547259045417582592)]} 0 0
   [junit4]   2> 591783 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1547259045418631168)]} 0 1
   [junit4]   2> 591785 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1547259045422825472)]} 0 2
   [junit4]   2> 591788 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1547259045423874048)]} 0 0
   [junit4]   2> 591792 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1547259045425971200)]} 0 3
   [junit4]   2> 591793 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1547259045430165504)]} 0 0
   [junit4]   2> 591793 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1547259045431214080)]} 0 0
   [junit4]   2> 591796 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1547259045434359808)]} 0 0
   [junit4]   2> 591797 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1547259045435408384)]} 0 0
   [junit4]   2> 591804 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1547259045440651264)]} 0 2
   [junit4]   2> 591804 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1547259045442748416)]} 0 0
   [junit4]   2> 591805 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1547259045443796992)]} 0 0
   [junit4]   2> 591806 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1547259045444845568)]} 0 0
   [junit4]   2> 591807 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1547259045444845568)]} 0 0
   [junit4]   2> 591810 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1547259045445894144)]} 0 2
   [junit4]   2> 591812 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1547259045451137024)]} 0 1
   [junit4]   2> 591813 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1547259045451137024)]} 0 0
   [junit4]   2> 591813 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1547259045452185600)]} 0 0
   [junit4]   2> 591814 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1547259045453234176)]} 0 0
   [junit4]   2> 591817 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1547259045453234176)]} 0 2
   [junit4]   2> 591818 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1547259045457428480)]} 0 0
   [junit4]   2> 591819 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1547259045458477056)]} 0 0
   [junit4]   2> 591820 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1547259045459525632)]} 0 0
   [junit4]   2> 591825 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1547259045464768512)]} 0 0
   [junit4]   2> 591830 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1547259045465817088)]} 0 4
   [junit4]   2> 591831 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1547259045471059968)]} 0 0
   [junit4]   2> 591832 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1547259045472108544)]} 0 0
   [junit4]   2> 591838 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1547259045478400000)]} 0 5
   [junit4]   2> 591840 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1547259045480497152)]} 0 0
   [junit4]   2> 591842 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1547259045481545728)]} 0 0
   [junit4]   2> 591843 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1547259045482594304)]} 0 0
   [junit4]   2> 591845 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1547259045484691456)]} 0 0
   [junit4]   2> 591846 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1547259045485740032)]} 0 1
   [junit4]   2> 591848 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1547259045487837184)]} 0 0
   [junit4]   2> 591849 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1547259045489934336)]} 0 0
   [junit4]   2> 591851 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1547259045492031488)]} 0 0
   [junit4]   2> 591852 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1547259045493080064)]} 0 0
   [junit4]   2> 591853 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1547259045494128640)]} 0 0
   [junit4]   2> 591854 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1547259045494128640)]} 0 0
   [junit4]   2> 591854 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1547259045495177216)]} 0 0
   [junit4]   2> 591855 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1547259045496225792)]} 0 0
   [junit4]   2> 591856 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1547259045497274368)]} 0 0
   [junit4]   2> 591857 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1547259045497274368)]} 0 0
   [junit4]   2> 591858 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1547259045498322944)]} 0 0
   [junit4]   2> 591860 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1547259045499371520)]} 0 1
   [junit4]   2> 591861 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1547259045501468672)]} 0 0
   [junit4]   2> 591861 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1547259045502517248)]} 0 0
   [junit4]   2> 591863 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1547259045504614400)]} 0 0
   [junit4]   2> 591864 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1547259045504614400)]} 0 0
   [junit4]   2> 591864 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1547259045505662976)]} 0 0
   [junit4]   2> 591865 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1547259045506711552)]} 0 0
   [junit4]   2> 591866 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1547259045506711552)]} 0 0
   [junit4]   2> 591866 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1547259045507760128)]} 0 0
   [junit4]   2> 591866 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1547259045507760128)]} 0 0
   [junit4]   2> 591867 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1547259045508808704)]} 0 0
   [junit4]   2> 591867 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1547259045508808704)]} 0 0
   [junit4]   2> 591868 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1547259045509857280)]} 0 0
   [junit4]   2> 591869 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1547259045510905856)]} 0 0
   [junit4]   2> 591870 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1547259045510905856)]} 0 0
   [junit4]   2> 591870 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1547259045511954432)]} 0 0
   [junit4]   2> 591872 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1547259045514051584)]} 0 0
   [junit4]   2> 591872 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1547259045514051584)]} 0 0
   [junit4]   2> 591873 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1547259045515100160)]} 0 0
   [junit4]   2> 591874 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1547259045516148736)]} 0 0
   [junit4]   2> 591875 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1547259045516148736)]} 0 0
   [junit4]   2> 591875 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1547259045517197312)]} 0 0
   [junit4]   2> 591876 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1547259045518245888)]} 0 0
   [junit4]   2> 591877 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1547259045518245888)]} 0 0
   [junit4]   2> 591877 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1547259045519294464)]} 0 0
   [junit4]   2> 591878 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1547259045520343040)]} 0 0
   [junit4]   2> 591879 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1547259045520343040)]} 0 0
   [junit4]   2> 591879 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1547259045521391616)]} 0 0
   [junit4]   2> 591880 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1547259045522440192)]} 0 0
   [junit4]   2> 591881 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1547259045523488768)]} 0 0
   [junit4]   2> 591881 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1547259045523488768)]} 0 0
   [junit4]   2> 591882 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1547259045524537344)]} 0 0
   [junit4]   2> 591884 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1547259045524537344)]} 0 1
   [junit4]   2> 591884 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1547259045526634496)]} 0 0
   [junit4]   2> 591885 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1547259045527683072)]} 0 0
   [junit4]   2> 591885 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1547259045527683072)]} 0 0
   [junit4]   2> 591886 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1547259045528731648)]} 0 0
   [junit4]   2> 591887 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1547259045529780224)]} 0 0
   [junit4]   2> 591887 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1547259045529780224)]} 0 0
   [junit4]   2> 591889 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1547259045530828800)]} 0 1
   [junit4]   2> 591889 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1547259045531877376)]} 0 0
   [junit4]   2> 591890 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1547259045532925952)]} 0 0
   [junit4]   2> 591891 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1547259045533974528)]} 0 0
   [junit4]   2> 591891 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1547259045533974528)]} 0 0
   [junit4]   2> 591892 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1547259045535023104)]} 0 0
   [junit4]   2> 591893 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1547259045535023104)]} 0 0
   [junit4]   2> 591893 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1547259045536071680)]} 0 0
   [junit4]   2> 591894 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1547259045537120256)]} 0 0
   [junit4]   2> 591896 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1547259045537120256)]} 0 0
   [junit4]   2> 591897 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1547259045539217408)]} 0 0
   [junit4]   2> 591897 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1547259045540265984)]} 0 0
   [junit4]   2> 591897 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1547259045540265984)]} 0 0
   [junit4]   2> 591897 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1547259045540265985)]} 0 0
   [junit4]   2> 591899 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1547259045542363136)]} 0 0
   [junit4]   2> 591899 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1547259045542363136)]} 0 0
   [junit4]   2> 591899 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1547259045542363137)]} 0 0
   [junit4]   2> 591900 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1547259045543411712)]} 0 0
   [junit4]   2> 591901 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1547259045544460288)]} 0 0
   [junit4]   2> 591901 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1547259045544460288)]} 0 0
   [junit4]   2> 591902 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 591904 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6008d104
   [junit4]   2> 591951 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@12745567[collection1] main]
   [junit4]   2> 591951 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 591951 INFO  
(searcherExecutor-1814-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@12745567[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 591951 INFO  (qtp1069764706-4170) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 50
   [junit4]   2> 591951 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 591951 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@491b8538
   [junit4]   2> 591967 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5093be8a[collection1] main]
   [junit4]   2> 591967 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 591968 INFO  
(searcherExecutor-1825-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5093be8a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 591968 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 14
   [junit4]   2> 591969 INFO  (qtp1069764706-4159) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 591969 INFO  (qtp1069764706-4159) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 591970 INFO  (qtp1069764706-4163) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 591971 INFO  (qtp1069764706-4163) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 591972 INFO  (qtp1069764706-4166) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 591976 INFO  (qtp199253719-4179) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:64114//collection1&rows=10&version=2&q=*:*&NOW=1475581212635&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 591992 INFO  (qtp199253719-4184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212635&ids=122,134,124,15,4,115,104,107,108,141&isShard=true&shard.url=http://127.0.0.1:64114//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 591999 INFO  (qtp199253719-4188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:64114//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=25
   [junit4]   2> 592005 INFO  (qtp1069764706-4165) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592009 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:64114//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475581212668&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592011 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212668&ids=123,112,14,113,126,149,138,128,142,10&isShard=true&shard.url=http://127.0.0.1:64114//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592012 INFO  (qtp199253719-4182) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:64114//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 592015 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592017 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:64114//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475581212679&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592019 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212679&ids=134,14,135,4,15,126,127,139,106,131&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:64114//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592019 INFO  (qtp199253719-4181) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:64114//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 592021 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592022 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:64114//collection1&rows=10&version=2&q=*:*&NOW=1475581212683&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592024 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212683&ids=1,144,12,101,102,16,5,115,9,109&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:64114//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592024 INFO  (qtp199253719-4190) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:64114//collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 592024 INFO  (qtp1069764706-4164) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592029 INFO  (qtp199253719-4186) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:64114//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475581212689&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592030 INFO  (qtp199253719-4186) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212689&ids=11,110,144,133,111,138,139,119,109,141&isShard=true&shard.url=http://127.0.0.1:64114//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592032 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:64114//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 592033 INFO  (qtp1069764706-4162) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592046 INFO  (qtp199253719-4186) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:64114//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475581212696&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592047 INFO  (qtp199253719-4188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212696&ids=143,122,100,2,14,127,17,140,130,131&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:64114//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592050 INFO  (qtp199253719-4179) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:64114//collection1|[ff01::083]:33332/&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=16
   [junit4]   2> 592053 INFO  (qtp1069764706-4162) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592055 INFO  (qtp199253719-4181) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:64114//collection1&rows=10&version=2&q=*:*&NOW=1475581212716&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592056 INFO  (qtp199253719-4190) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212716&ids=123,13,124,14,103,115,148,17,109,10&isShard=true&shard.url=http://127.0.0.1:64114//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592058 INFO  (qtp199253719-4182) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:64114//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 592059 INFO  (qtp1069764706-4159) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592060 INFO  (qtp199253719-4183) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:64114//collection1&rows=10&version=2&q=*:*&NOW=1475581212722&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592062 INFO  (qtp199253719-4184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212722&ids=132,1,2,112,137,149,6,8,119,141&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:64114//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592063 INFO  (qtp199253719-4185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:64114//collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 592064 INFO  (qtp1069764706-4163) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592067 INFO  (qtp199253719-4188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:64114//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475581212728&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592068 INFO  (qtp199253719-4179) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212728&ids=11,12,100,123,146,114,148,127,109,10&isShard=true&shard.url=http://127.0.0.1:64114//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592069 INFO  (qtp199253719-4186) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:64114//collection1|[ff01::083]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 592070 INFO  (qtp1069764706-4166) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592071 INFO  (qtp199253719-4190) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:64114//collection1&rows=10&version=2&q=*:*&NOW=1475581212733&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 592072 INFO  (qtp199253719-4182) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1475581212733&ids=134,102,147,5,17,1001,118,8,9,140&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:64114//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 592074 INFO  (qtp199253719-4181) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:64114//collection1&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 592076 INFO  (qtp1069764706-4165) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 592077 INFO  (qtp199253719-4184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:64114//collection1|

[...truncated too long message...]

read-1) [    x:collection1] o.a.s.u.LoggingInfoStream 
[DW][coreCloseExecutor-151-thread-1]: coreCloseExecutor-151-thread-1 
finishFullFlush success=true
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
apply all deletes during flush
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
now apply all deletes for all segments maxDoc=1000
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-151-thread-1]: 
applyDeletes: open segment readers took 0 msec
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-151-thread-1]: 
applyDeletes: no segments; skipping
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-151-thread-1]: 
prune sis=segments_3: _1(7.0.0):C1000/999:delGen=1 minGen=15 packetCount=0
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-151-thread-1]: 
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-151-thread-1]: 
now merge
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-151-thread-1]:  
 index: _1(7.0.0):C1000/999:delGen=1
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-151-thread-1]:  
 no more merges pending; now return
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
waitForMerges
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
waitForMerges done
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
commit: start
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
commit: enter lock
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
commit: now prepare
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
prepareCommit: flush
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]:  
 index before flush _1(7.0.0):C1000/999:delGen=1
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-151-thread-1]: 
startFullFlush
   [junit4]   2> 69971 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
apply all deletes during flush
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
now apply all deletes for all segments maxDoc=1000
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-151-thread-1]: 
applyDeletes: open segment readers took 0 msec
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-151-thread-1]: 
applyDeletes: no segments; skipping
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-151-thread-1]: 
prune sis=segments_3: _1(7.0.0):C1000/999:delGen=1 minGen=15 packetCount=0
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-151-thread-1]: 
coreCloseExecutor-151-thread-1 finishFullFlush success=true
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
startCommit(): start
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]:  
 skip startCommit(): no changes pending
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-151-thread-1]: 
delete []
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
commit: pendingCommit == null; skip
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
commit: took 1.9 msec
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
commit: done
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
rollback
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
all running merges have aborted
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
rollback: done finish merges
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-151-thread-1]: 
abort
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-151-thread-1]: 
done abort success=true
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-151-thread-1]: 
rollback: infos=_1(7.0.0):C1000/999:delGen=1
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-151-thread-1]: 
now checkpoint "_1(7.0.0):C1000/999:delGen=1" [1 segments ; isCommit = false]
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-151-thread-1]: 
delete []
   [junit4]   2> 69973 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-151-thread-1]: 
0 msec to checkpoint
   [junit4]   2> 69975 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-151-thread-1]: 
delete []
   [junit4]   2> 69975 INFO  (coreCloseExecutor-151-thread-1) [    
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-151-thread-1]: 
delete []
   [junit4]   2> 69976 INFO  
(SUITE-TestSolrJErrorHandling-seed#[58B0E8C6C0D5419D]-worker) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3f40fd67{/solr,null,UNAVAILABLE}
   [junit4]   2> 69980 INFO  
(SUITE-TestSolrJErrorHandling-seed#[58B0E8C6C0D5419D]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1313, maxMBSortInHeap=5.868273932015866, 
sim=ClassicSimilarity, locale=sv, timezone=EST5EDT
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 
(64-bit)/cpus=3,threads=1,free=34788848,total=181874688
   [junit4]   2> NOTE: All tests run in this JVM: [TestCoreAdmin, 
SolrExampleStreamingTest, TestCollectionAdminRequest, 
CloudSolrClientBuilderTest, TestUpdateRequestCodec, TestSolrJErrorHandling]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSolrJErrorHandling -Dtests.seed=58B0E8C6C0D5419D 
-Dtests.slow=true -Dtests.locale=sv -Dtests.timezone=EST5EDT 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | TestSolrJErrorHandling (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 
object(s) that were not released!!! [InternalHttpClient]
   [junit4]    > 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]    >        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:262)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:209)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:201)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.<init>(CloudSolrClient.java:393)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.<init>(CloudSolrClient.java:100)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient$Builder.build(CloudSolrClient.java:1673)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClientBuilderTest.testNoZkHostSpecified(CloudSolrClientBuilderTest.java:36)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([58B0E8C6C0D5419D]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [50/85 (2!)] on J0 in 45.67s, 4 tests, 1 failure <<< 
FAILURES!

[...truncated 59755 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build # 6157 - Still Unstable!

Reply via email to