Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19187/
Java: 64bit/jdk-9-ea+160 -XX:-UseCompressedOops -XX:+UseParallelGC

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.search.LargeFieldTest

Error Message:


Stack Trace:
java.lang.AssertionError
        at __randomizedtesting.SeedInfo.seed([4E896977EBBAC264]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at org.junit.Assert.assertNull(Assert.java:562)
        at 
org.apache.solr.search.LargeFieldTest.initManagedSchemaCore(LargeFieldTest.java:61)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:547)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:847)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)


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:259)
  at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:166)
  at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)  at 
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
  at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)  at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
  at 
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
  at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
  at java.base/java.lang.Thread.run(Thread.java:844) ,time=1}

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:259)
        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:166)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        at 
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
        at 
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
        at java.base/java.lang.Thread.run(Thread.java:844)
,time=1}
        at 
__randomizedtesting.SeedInfo.seed([4E896977EBBAC264:C6DD56AD4546AF9C]: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:1186)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1127)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:987)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:547)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 11251 lines...]
   [junit4] Suite: org.apache.solr.search.LargeFieldTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.search.LargeFieldTest_4E896977EBBAC264-001/init-core-data-001
   [junit4]   2> 344113 WARN  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=49 numCloses=49
   [junit4]   2> 344113 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 344116 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 344118 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 344119 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 344210 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 344223 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=one_field_no_dynamic_field_unique_key
   [junit4]   2> 344225 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema one_field_no_dynamic_field_unique_key/1.1 
with uniqueid field str
   [junit4]   2> 344360 WARN  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 344364 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bb82723
   [junit4]   2> 344364 WARN  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 344368 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bb82723
   [junit4]   2> 344368 WARN  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 344368 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bb82723
   [junit4]   2> 344370 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 344460 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 344469 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema 
name=one_field_no_dynamic_field_unique_key
   [junit4]   2> 344471 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema one_field_no_dynamic_field_unique_key/1.1 
with uniqueid field str
   [junit4]   2> 344475 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 344475 WARN  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 344475 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bb82723
   [junit4]   2> 344475 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 344475 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.search.LargeFieldTest_4E896977EBBAC264-001/init-core-data-001/]
   [junit4]   2> 344479 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=15, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=55.75390625, 
floorSegmentMB=1.2861328125, forceMergeDeletesPctAllowed=26.796308836543638, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.42642693659746866
   [junit4]   2> 364729 WARN  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, registerPath=/solr,/v2, 
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 364882 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 364882 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 364883 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=23, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=52.474609375, 
floorSegmentMB=1.6337890625, forceMergeDeletesPctAllowed=11.6249157629736, 
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 364883 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@47520b24[collection1] main]
   [junit4]   2> 364884 WARN  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 364884 INFO  (coreLoadExecutor-1907-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 364884 INFO  (searcherExecutor-1908-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@47520b24[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 364885 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 364891 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={wt=xml} status=500 QTime=5
   [junit4]   2> 364891 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 364891 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1002099095
   [junit4]   2> 364891 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 364893 INFO  (coreCloseExecutor-1912-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@449eb66e
   [junit4]   2> 364894 INFO  (coreCloseExecutor-1912-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1151252078
   [junit4]   2> 364896 INFO  
(SUITE-LargeFieldTest-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=RandomSimilarity(queryNorm=true): {}, locale=yo-NG, timezone=PLT
   [junit4]   2> NOTE: Linux 4.4.0-66-generic amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=12,threads=1,free=171784232,total=480772096
   [junit4]   2> NOTE: All tests run in this JVM: [SolrMetricManagerTest, 
TestFoldingMultitermQuery, TestDistribIDF, TestShortCircuitedRequests, 
TestSchemaSimilarityResource, NumericFieldsTest, CopyFieldTest, 
TestMaxScoreQueryParser, ResponseHeaderTest, 
TestSolrQueryParserDefaultOperatorResource, ZkNodePropsTest, 
TestRawTransformer, QueryEqualityTest, XsltUpdateRequestHandlerTest, 
SolrCloudExampleTest, HdfsRestartWhileUpdatingTest, TestFieldTypeResource, 
TestJoin, TestOrdValues, CollectionReloadTest, PreAnalyzedUpdateProcessorTest, 
TestOmitPositions, TestComplexPhraseLeadingWildcard, BlockJoinFacetDistribTest, 
SolrGangliaReporterTest, TestOnReconnectListenerSupport, TestJmxMonitoredMap, 
TestLeaderInitiatedRecoveryThread, TestImplicitCoreProperties, TestRealTimeGet, 
ReturnFieldsTest, PeerSyncWithIndexFingerprintCachingTest, TestLazyCores, 
DistributedSpellCheckComponentTest, TestClassicSimilarityFactory, 
QueryElevationComponentTest, UniqFieldsUpdateProcessorFactoryTest, 
TestCollationFieldDocValues, TestHighlightDedupGrouping, TestRTimerTree, 
CustomCollectionTest, StandardRequestHandlerTest, CacheHeaderTest, 
DocumentAnalysisRequestHandlerTest, TestLRUCache, SaslZkACLProviderTest, 
SolrRequestParserTest, BasicZkTest, TestRandomFaceting, 
AnalysisErrorHandlingTest, TestLMJelinekMercerSimilarityFactory, 
TestNamedUpdateProcessors, LargeFieldTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=LargeFieldTest 
-Dtests.seed=4E896977EBBAC264 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=yo-NG -Dtests.timezone=PLT -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | LargeFieldTest (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4E896977EBBAC264]:0)
   [junit4]    >        at 
org.apache.solr.search.LargeFieldTest.initManagedSchemaCore(LargeFieldTest.java:61)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:547)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4] Completed [139/700 (1!)] on J0 in 20.81s, 0 tests, 1 failure <<< 
FAILURES!

[...truncated 696 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/init-core-data-001
   [junit4]   2> 1586966 WARN  
(SUITE-TestDistributedSearch-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=158 numCloses=158
   [junit4]   2> 1586966 INFO  
(SUITE-TestDistributedSearch-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 1586967 INFO  
(SUITE-TestDistributedSearch-seed#[4E896977EBBAC264]-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> 1586967 INFO  
(SUITE-TestDistributedSearch-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_bm/s
   [junit4]   2> 1587749 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1587750 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 1587751 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5dde300e{/_bm/s,null,AVAILABLE}
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1d3d918{HTTP/1.1,[http/1.1]}{127.0.0.1:46089}
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] o.e.j.s.Server 
Started @1589467ms
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_bm/s, 
hostPort=46089, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/control/cores}
   [junit4]   2> 1587753 ERROR 
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1587753 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-16T23:27:06.084912Z
   [junit4]   2> 1587754 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/control/solr.xml
   [junit4]   2> 1587843 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/control/cores
   [junit4]   2> 1587843 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1587844 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1587922 WARN  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1587925 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1587955 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1588095 WARN  (coreLoadExecutor-5758-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> 1588098 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1588126 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1588126 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1588126 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1588126 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@31890313
   [junit4]   2> 1588127 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=15, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=90.2939453125, 
floorSegmentMB=0.8486328125, forceMergeDeletesPctAllowed=5.694849770298337, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5588845704718087
   [junit4]   2> 1588380 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1588380 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1588382 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1588382 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1588383 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=41, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=42.2099609375, 
floorSegmentMB=1.013671875, forceMergeDeletesPctAllowed=7.228377848005464, 
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.13620109689915447
   [junit4]   2> 1588383 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@ff33c90[collection1] main]
   [junit4]   2> 1588384 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1588447 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1588728 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1588728 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1588728 INFO  (coreLoadExecutor-5758-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1562070825890217984
   [junit4]   2> 1588731 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1588731 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1588731 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1588733 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1588736 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1588738 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1588739 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1588741 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1588742 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ff33c90[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1590504 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1590507 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 1590508 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@76f32f03{/_bm/s,null,AVAILABLE}
   [junit4]   2> 1590509 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1a5f90f7{HTTP/1.1,[http/1.1]}{127.0.0.1:44273}
   [junit4]   2> 1590509 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] o.e.j.s.Server 
Started @1592222ms
   [junit4]   2> 1590509 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_bm/s, 
hostPort=44273, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/shard0/cores}
   [junit4]   2> 1590509 ERROR 
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1590510 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1590510 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1590510 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1590510 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-16T23:27:08.841157Z
   [junit4]   2> 1590510 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1590659 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/shard0/cores
   [junit4]   2> 1590659 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1590660 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1590740 WARN  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1590741 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1590759 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1590853 WARN  (coreLoadExecutor-5769-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> 1590856 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1590881 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1590881 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1590881 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1590881 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@31890313
   [junit4]   2> 1590882 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=15, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=90.2939453125, 
floorSegmentMB=0.8486328125, forceMergeDeletesPctAllowed=5.694849770298337, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5588845704718087
   [junit4]   2> 1591144 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1591144 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1591147 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1591147 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1591148 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=41, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=42.2099609375, 
floorSegmentMB=1.013671875, forceMergeDeletesPctAllowed=7.228377848005464, 
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.13620109689915447
   [junit4]   2> 1591149 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@137e09c3[collection1] main]
   [junit4]   2> 1591149 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1591202 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1591599 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1591599 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1591601 INFO  (coreLoadExecutor-5769-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1562070828902776832
   [junit4]   2> 1591602 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1591602 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1591602 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1591605 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1591607 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1591609 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1591611 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1591614 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1591615 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@137e09c3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1592064 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1592069 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1562070829390364672)} 0 2
   [junit4]   2> 1592073 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1562070829395607552)} 0 2
   [junit4]   2> 1592076 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[1 (1562070829398753280)]} 0 1
   [junit4]   2> 1592079 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[1 (1562070829401899008)]} 0 2
   [junit4]   2> 1592081 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[2 (1562070829405044736)]} 0 0
   [junit4]   2> 1592082 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[2 (1562070829407141888)]} 0 0
   [junit4]   2> 1592083 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[3 (1562070829408190464)]} 0 0
   [junit4]   2> 1592084 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[3 (1562070829409239040)]} 0 0
   [junit4]   2> 1592085 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[4 (1562070829409239040)]} 0 0
   [junit4]   2> 1592085 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[4 (1562070829410287616)]} 0 0
   [junit4]   2> 1592086 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[5 (1562070829410287616)]} 0 0
   [junit4]   2> 1592086 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[5 (1562070829411336192)]} 0 0
   [junit4]   2> 1592087 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[6 (1562070829412384768)]} 0 0
   [junit4]   2> 1592087 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[6 (1562070829412384768)]} 0 0
   [junit4]   2> 1592088 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[7 (1562070829413433344)]} 0 0
   [junit4]   2> 1592089 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[7 (1562070829413433344)]} 0 0
   [junit4]   2> 1592089 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[8 (1562070829414481920)]} 0 0
   [junit4]   2> 1592090 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[8 (1562070829415530496)]} 0 0
   [junit4]   2> 1592090 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[9 (1562070829415530496)]} 0 0
   [junit4]   2> 1592091 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[9 (1562070829416579072)]} 0 0
   [junit4]   2> 1592091 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1592092 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5b385799 commitCommandVersion:0
   [junit4]   2> 1592103 INFO  (qtp1057698359-13669) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2656c34b[collection1] main]
   [junit4]   2> 1592105 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1592106 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2656c34b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 1592106 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 14
   [junit4]   2> 1592106 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1592106 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@632b15f8 commitCommandVersion:0
   [junit4]   2> 1592114 INFO  (qtp1059442885-13686) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@f41dea1[collection1] main]
   [junit4]   2> 1592114 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1592116 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f41dea1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 1592117 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 10
   [junit4]   2> 1592121 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[10 (1562070829444890624)]} 0 3
   [junit4]   2> 1592125 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[10 (1562070829449084928)]} 0 2
   [junit4]   2> 1592126 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[11 (1562070829452230656)]} 0 0
   [junit4]   2> 1592128 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[11 (1562070829454327808)]} 0 1
   [junit4]   2> 1592129 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[12 (1562070829456424960)]} 0 0
   [junit4]   2> 1592130 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[12 (1562070829456424960)]} 0 0
   [junit4]   2> 1592130 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[13 (1562070829457473536)]} 0 0
   [junit4]   2> 1592131 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[13 (1562070829458522112)]} 0 0
   [junit4]   2> 1592132 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[1001 (1562070829459570688)]} 0 0
   [junit4]   2> 1592133 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[1001 (1562070829460619264)]} 0 0
   [junit4]   2> 1592134 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[14 (1562070829461667840)]} 0 0
   [junit4]   2> 1592135 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[14 (1562070829461667840)]} 0 0
   [junit4]   2> 1592135 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[15 (1562070829462716416)]} 0 0
   [junit4]   2> 1592136 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[15 (1562070829462716416)]} 0 0
   [junit4]   2> 1592136 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[16 (1562070829463764992)]} 0 0
   [junit4]   2> 1592137 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[16 (1562070829463764992)]} 0 0
   [junit4]   2> 1592137 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[17 (1562070829464813568)]} 0 0
   [junit4]   2> 1592138 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[17 (1562070829465862144)]} 0 0
   [junit4]   2> 1592139 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[100 (1562070829465862144)]} 0 0
   [junit4]   2> 1592139 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[100 (1562070829466910720)]} 0 0
   [junit4]   2> 1592140 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[101 (1562070829467959296)]} 0 0
   [junit4]   2> 1592141 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[101 (1562070829467959296)]} 0 0
   [junit4]   2> 1592141 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[102 (1562070829469007872)]} 0 0
   [junit4]   2> 1592142 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[102 (1562070829469007872)]} 0 0
   [junit4]   2> 1592142 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[103 (1562070829470056448)]} 0 0
   [junit4]   2> 1592142 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[103 (1562070829470056448)]} 0 0
   [junit4]   2> 1592143 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[104 (1562070829471105024)]} 0 0
   [junit4]   2> 1592143 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[104 (1562070829471105024)]} 0 0
   [junit4]   2> 1592144 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[105 (1562070829472153600)]} 0 0
   [junit4]   2> 1592145 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[105 (1562070829472153600)]} 0 0
   [junit4]   2> 1592160 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[106 (1562070829473202176)]} 0 0
   [junit4]   2> 1592161 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[106 (1562070829489979392)]} 0 0
   [junit4]   2> 1592162 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[107 (1562070829491027968)]} 0 0
   [junit4]   2> 1592163 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[107 (1562070829491027968)]} 0 0
   [junit4]   2> 1592163 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[108 (1562070829492076544)]} 0 0
   [junit4]   2> 1592164 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[108 (1562070829493125120)]} 0 0
   [junit4]   2> 1592165 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[109 (1562070829494173696)]} 0 0
   [junit4]   2> 1592165 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[109 (1562070829494173696)]} 0 0
   [junit4]   2> 1592166 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[110 (1562070829495222272)]} 0 0
   [junit4]   2> 1592167 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[110 (1562070829495222272)]} 0 0
   [junit4]   2> 1592167 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[111 (1562070829496270848)]} 0 0
   [junit4]   2> 1592167 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[111 (1562070829496270848)]} 0 0
   [junit4]   2> 1592168 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[112 (1562070829497319424)]} 0 0
   [junit4]   2> 1592169 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[112 (1562070829497319424)]} 0 0
   [junit4]   2> 1592169 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[113 (1562070829498368000)]} 0 0
   [junit4]   2> 1592169 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[113 (1562070829498368000)]} 0 0
   [junit4]   2> 1592170 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[114 (1562070829499416576)]} 0 0
   [junit4]   2> 1592170 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[114 (1562070829499416576)]} 0 0
   [junit4]   2> 1592171 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[115 (1562070829500465152)]} 0 0
   [junit4]   2> 1592171 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[115 (1562070829500465152)]} 0 0
   [junit4]   2> 1592172 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[116 (1562070829501513728)]} 0 0
   [junit4]   2> 1592172 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[116 (1562070829501513728)]} 0 0
   [junit4]   2> 1592173 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[117 (1562070829502562304)]} 0 0
   [junit4]   2> 1592173 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[117 (1562070829502562304)]} 0 0
   [junit4]   2> 1592174 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[118 (1562070829503610880)]} 0 0
   [junit4]   2> 1592175 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[118 (1562070829503610880)]} 0 0
   [junit4]   2> 1592175 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[119 (1562070829504659456)]} 0 0
   [junit4]   2> 1592176 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[119 (1562070829505708032)]} 0 0
   [junit4]   2> 1592177 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[120 (1562070829505708032)]} 0 0
   [junit4]   2> 1592177 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[120 (1562070829506756608)]} 0 0
   [junit4]   2> 1592178 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[121 (1562070829506756608)]} 0 0
   [junit4]   2> 1592178 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[121 (1562070829507805184)]} 0 0
   [junit4]   2> 1592179 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[122 (1562070829507805184)]} 0 0
   [junit4]   2> 1592179 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[122 (1562070829508853760)]} 0 0
   [junit4]   2> 1592180 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[123 (1562070829508853760)]} 0 0
   [junit4]   2> 1592180 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[123 (1562070829509902336)]} 0 0
   [junit4]   2> 1592181 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[124 (1562070829510950912)]} 0 0
   [junit4]   2> 1592181 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[124 (1562070829510950912)]} 0 0
   [junit4]   2> 1592182 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[125 (1562070829511999488)]} 0 0
   [junit4]   2> 1592182 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[125 (1562070829511999488)]} 0 0
   [junit4]   2> 1592183 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[126 (1562070829513048064)]} 0 0
   [junit4]   2> 1592183 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[126 (1562070829513048064)]} 0 0
   [junit4]   2> 1592184 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[127 (1562070829514096640)]} 0 0
   [junit4]   2> 1592185 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[127 (1562070829514096640)]} 0 0
   [junit4]   2> 1592185 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[128 (1562070829515145216)]} 0 0
   [junit4]   2> 1592186 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[128 (1562070829515145216)]} 0 0
   [junit4]   2> 1592186 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[129 (1562070829516193792)]} 0 0
   [junit4]   2> 1592187 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[129 (1562070829516193792)]} 0 0
   [junit4]   2> 1592187 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[130 (1562070829517242368)]} 0 0
   [junit4]   2> 1592188 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[130 (1562070829518290944)]} 0 0
   [junit4]   2> 1592189 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[131 (1562070829518290944)]} 0 0
   [junit4]   2> 1592189 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[131 (1562070829519339520)]} 0 0
   [junit4]   2> 1592190 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[132 (1562070829519339520)]} 0 0
   [junit4]   2> 1592190 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[132 (1562070829520388096)]} 0 0
   [junit4]   2> 1592191 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[133 (1562070829521436672)]} 0 0
   [junit4]   2> 1592191 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[133 (1562070829521436672)]} 0 0
   [junit4]   2> 1592194 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[134 (1562070829523533824)]} 0 0
   [junit4]   2> 1592194 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[134 (1562070829524582400)]} 0 0
   [junit4]   2> 1592194 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[135 (1562070829524582400)]} 0 0
   [junit4]   2> 1592202 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[135 (1562070829531922432)]} 0 0
   [junit4]   2> 1592202 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[136 (1562070829532971008)]} 0 0
   [junit4]   2> 1592203 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[136 (1562070829534019584)]} 0 0
   [junit4]   2> 1592203 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[137 (1562070829534019584)]} 0 0
   [junit4]   2> 1592204 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[137 (1562070829535068160)]} 0 0
   [junit4]   2> 1592204 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[138 (1562070829535068160)]} 0 0
   [junit4]   2> 1592204 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[138 (1562070829535068161)]} 0 0
   [junit4]   2> 1592205 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[139 (1562070829536116736)]} 0 0
   [junit4]   2> 1592205 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[139 (1562070829536116736)]} 0 0
   [junit4]   2> 1592206 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[140 (1562070829537165312)]} 0 0
   [junit4]   2> 1592206 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[140 (1562070829537165312)]} 0 0
   [junit4]   2> 1592207 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[141 (1562070829538213888)]} 0 0
   [junit4]   2> 1592207 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[141 (1562070829538213888)]} 0 0
   [junit4]   2> 1592208 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[142 (1562070829538213889)]} 0 0
   [junit4]   2> 1592208 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[142 (1562070829539262464)]} 0 0
   [junit4]   2> 1592209 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[143 (1562070829539262464)]} 0 0
   [junit4]   2> 1592209 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[143 (1562070829540311040)]} 0 0
   [junit4]   2> 1592209 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[144 (1562070829540311040)]} 0 0
   [junit4]   2> 1592210 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[144 (1562070829541359616)]} 0 0
   [junit4]   2> 1592210 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[145 (1562070829541359616)]} 0 0
   [junit4]   2> 1592211 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[145 (1562070829542408192)]} 0 0
   [junit4]   2> 1592211 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[146 (1562070829542408192)]} 0 0
   [junit4]   2> 1592212 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[146 (1562070829543456768)]} 0 0
   [junit4]   2> 1592213 INFO  (qtp1057698359-13669) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[147 (1562070829544505344)]} 0 0
   [junit4]   2> 1592213 INFO  (qtp1059442885-13693) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[147 (1562070829544505344)]} 0 0
   [junit4]   2> 1592214 INFO  (qtp1057698359-13674) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[148 (1562070829544505345)]} 0 0
   [junit4]   2> 1592214 INFO  (qtp1059442885-13692) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[148 (1562070829545553920)]} 0 0
   [junit4]   2> 1592214 INFO  (qtp1057698359-13675) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[149 (1562070829545553920)]} 0 0
   [junit4]   2> 1592215 INFO  (qtp1059442885-13686) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={wt=javabin&version=2}{add=[149 (1562070829546602496)]} 0 0
   [junit4]   2> 1592215 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1592215 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5b385799 commitCommandVersion:0
   [junit4]   2> 1592228 INFO  (qtp1057698359-13676) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@26a9a990[collection1] main]
   [junit4]   2> 1592230 INFO  (searcherExecutor-5759-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26a9a990[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 1592230 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1592230 INFO  (qtp1057698359-13676) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 15
   [junit4]   2> 1592231 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1592231 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@632b15f8 commitCommandVersion:0
   [junit4]   2> 1592242 INFO  (qtp1059442885-13691) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@354620d9[collection1] main]
   [junit4]   2> 1592242 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1592243 INFO  (searcherExecutor-5770-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@354620d9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 1592243 INFO  (qtp1059442885-13691) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_bm/s path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 1592244 INFO  (qtp1057698359-13669) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1592244 INFO  (qtp1057698359-13669) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1592245 INFO  (qtp1057698359-13674) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1592245 INFO  (qtp1057698359-13674) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1592245 INFO  (qtp1057698359-13675) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592251 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830578&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592252 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830578&ids=132,147,149,6,17,117,119,108,140,142&isShard=true&shard.url=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592254 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 1592255 INFO  (qtp1057698359-13676) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592261 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830588&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592263 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830588&ids=121,1,134,101,102,135,124,114,127,7&isShard=true&shard.url=[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592265 INFO  (qtp1059442885-13692) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 1592266 INFO  (qtp1057698359-13669) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592268 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&rows=10&version=2&q=*:*&NOW=1489706830598&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592270 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830598&ids=11,13,101,146,4,126,116,106,118,142&isShard=true&shard.url=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592271 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1592273 INFO  (qtp1057698359-13669) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592276 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::083]:33332/_bm/s|[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&rows=10&version=2&q=*:*&NOW=1489706830605&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592277 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830605&ids=132,14,103,15,136,16,137,148,138,1001&isShard=true&shard.url=[ff01::083]:33332/_bm/s|[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592278 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::083]:33332/_bm/s|[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1592280 INFO  (qtp1057698359-13675) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592282 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s 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:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s|[ff01::213]:33332/_bm/s|[ff01::114]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830612&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592282 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830612&ids=11,123,14,4,6,139,128,109,140,10&isShard=true&shard.url=http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s|[ff01::213]:33332/_bm/s|[ff01::114]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592283 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s|[ff01::213]:33332/_bm/s|[ff01::114]:33332/_bm/s&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1592285 INFO  (qtp1057698359-13675) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592286 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830617&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592287 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830617&ids=122,144,1,124,103,114,138,116,9,119&isShard=true&shard.url=http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592288 INFO  (qtp1059442885-13692) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1592289 INFO  (qtp1057698359-13675) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592291 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&rows=10&version=2&q=*:*&NOW=1489706830621&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592297 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830621&ids=110,133,15,136,4,115,104,149,128,109&isShard=true&shard.url=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592298 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 1592299 INFO  (qtp1057698359-13675) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592301 INFO  (qtp1059442885-13692) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::213]:33332/_bm/s|[ff01::083]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::114]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830631&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592302 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830631&ids=144,1,2,13,113,147,5,118,141,120&isShard=true&shard.url=[ff01::213]:33332/_bm/s|[ff01::083]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::114]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592303 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::213]:33332/_bm/s|[ff01::083]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::114]:33332/_bm/s&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1592304 INFO  (qtp1057698359-13676) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592306 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s 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:44273/_bm/s/collection1|[ff01::213]:33332/_bm/s|[ff01::114]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830636&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592307 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830636&ids=11,101,112,2,13,113,104,127,107,142&isShard=true&shard.url=http://127.0.0.1:44273/_bm/s/collection1|[ff01::213]:33332/_bm/s|[ff01::114]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592309 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=http://127.0.0.1:44273/_bm/s/collection1|[ff01::213]:33332/_bm/s|[ff01::114]:33332/_bm/s&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1592310 INFO  (qtp1057698359-13676) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592312 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:44273/_bm/s/collection1|[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830643&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592314 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830643&ids=143,121,145,147,4,137,7,129,9,141&isShard=true&shard.url=http://127.0.0.1:44273/_bm/s/collection1|[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592315 INFO  (qtp1059442885-13692) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=http://127.0.0.1:44273/_bm/s/collection1|[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1592316 INFO  (qtp1057698359-13676) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592317 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830648&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592319 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830648&ids=121,144,135,147,103,114,104,137,1001,109&isShard=true&shard.url=[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592320 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::114]:33332/_bm/s|[ff01::213]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1592321 INFO  (qtp1057698359-13675) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592323 INFO  (qtp1059442885-13692) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::114]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s|[ff01::213]:33332/_bm/s&rows=10&version=2&q=*:*&NOW=1489706830653&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592324 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830653&ids=110,133,100,134,113,115,127,118,130,141&isShard=true&shard.url=[ff01::114]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s|[ff01::213]:33332/_bm/s&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592325 INFO  (qtp1059442885-13693) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=[ff01::114]:33332/_bm/s|http://127.0.0.1:44273/_bm/s/collection1|[ff01::083]:33332/_bm/s|[ff01::213]:33332/_bm/s&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1592326 INFO  (qtp1057698359-13674) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1592328 INFO  (qtp1059442885-13687) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:44273/_bm/s/collection1&rows=10&version=2&q=*:*&NOW=1489706830658&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1592329 INFO  (qtp1059442885-13686) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489706830658&ids=123,145,3,14,146,147,126,117,139,107&isShard=true&shard.url=http://127.0.0.1:44273/_bm/s/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1592330 INFO  (qtp1059442885-13691) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/_bm/s path=/select 
params={q=*:*&shards=http://127.0.0.1:44273/_bm/s/collection1&sort=n_tl1+desc&wt=javabi

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

rch.test-seed#[4E896977EBBAC264]) [    ] o.a.s.c.CoreContainer Shutting down 
CoreContainer instance=1438398826
   [junit4]   2> 1618544 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1618545 INFO  (coreCloseExecutor-5814-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7b8cfcb6
   [junit4]   2> 1618554 INFO  (coreCloseExecutor-5814-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=2072837302
   [junit4]   2> 1618555 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1e84e057{/_bm/s,null,UNAVAILABLE}
   [junit4]   2> 1618556 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@2806533c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1618556 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1994620180
   [junit4]   2> 1618556 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1618556 INFO  (coreCloseExecutor-5816-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3b789ab1
   [junit4]   2> 1618565 INFO  (coreCloseExecutor-5816-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=997759665
   [junit4]   2> 1618566 INFO  
(TEST-TestDistributedSearch.test-seed#[4E896977EBBAC264]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@137633bc{/_bm/s,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=4E896977EBBAC264 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ca -Dtests.timezone=Australia/NSW -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 31.6s J1 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 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
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:259)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:166)
   [junit4]    >        at 
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
   [junit4]    >        at 
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514)
   [junit4]    >        at 
java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
   [junit4]    >        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]    >        at 
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1161)
   [junit4]    >        at 
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]    > ,time=1}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([4E896977EBBAC264:C6DD56AD4546AF9C]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1186)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1127)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:987)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:547)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 1618569 INFO  
(SUITE-TestDistributedSearch-seed#[4E896977EBBAC264]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_4E896977EBBAC264-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{severity=PostingsFormat(name=Memory), 
other_tl1=PostingsFormat(name=LuceneFixedGap), 
foo_sev_enum=Lucene50(blocksize=128), 
SubjectTerms_mfacet=PostingsFormat(name=LuceneFixedGap), 
a_n_tdt=Lucene50(blocksize=128), multiDefault=FST50, n_td1=FST50, 
b_n_tdt=PostingsFormat(name=LuceneFixedGap), n_ti1=Lucene50(blocksize=128), 
rnd_b=Lucene50(blocksize=128), n_tl1=FST50, a_s=PostingsFormat(name=Memory), 
oddField_s=PostingsFormat(name=Memory), 
n_tf1=PostingsFormat(name=LuceneFixedGap), lowerfilt1and2=FST50, 
a_t=PostingsFormat(name=LuceneFixedGap), foo_b=PostingsFormat(name=Memory), 
n_tdt1=PostingsFormat(name=LuceneFixedGap), lowerfilt=FST50, 
id=Lucene50(blocksize=128), text=PostingsFormat(name=Memory)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), 
foo_sev_enum=DocValuesFormat(name=Lucene70), n_l1=DocValuesFormat(name=Memory), 
intDefault=DocValuesFormat(name=Memory), n_dt1=DocValuesFormat(name=Direct), 
n_d1=DocValuesFormat(name=Memory), range_facet_l=DocValuesFormat(name=Memory), 
n_f1=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Memory), 
foo_d=DocValuesFormat(name=Lucene70), 
range_facet_i_dv=DocValuesFormat(name=Memory), 
foo_f=DocValuesFormat(name=Asserting), 
intDvoDefault=DocValuesFormat(name=Asserting), 
a_i_ni_p=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Memory)}, 
maxPointsInLeafNode=648, maxMBSortInHeap=6.163415348104737, 
sim=RandomSimilarity(queryNorm=true): {}, locale=ca, timezone=Australia/NSW
   [junit4]   2> NOTE: Linux 4.4.0-66-generic amd64/Oracle Corporation 9-ea 
(64-bit)/cpus=12,threads=1,free=248826720,total=509607936
   [junit4]   2> NOTE: All tests run in this JVM: [TestQueryUtils, 
TlogReplayBufferedWhileIndexingTest, TestFieldResource, ConnectionManagerTest, 
DeleteReplicaTest, TestInfoStreamLogging, TestDownShardTolerantSearch, 
TestPushWriter, DocValuesTest, ZkControllerTest, TestFilteredDocIdSet, 
TestCustomStream, TestConfigSetProperties, TestSha256AuthenticationProvider, 
TestSolrCloudWithSecureImpersonation, BasicFunctionalityTest, 
TimeZoneUtilsTest, TestDynamicFieldCollectionResource, ForceLeaderTest, 
TestSimpleTrackingShardHandler, HdfsSyncSliceTest, 
LeaderInitiatedRecoveryOnShardRestartTest, CloudMLTQParserTest, 
TestSubQueryTransformerCrossCore, TestMissingGroups, 
TestFuzzyAnalyzedSuggestions, TestTrie, SortByFunctionTest, CdcrUpdateLogTest, 
JsonValidatorTest, ShardRoutingCustomTest, HdfsRecoverLeaseTest, 
WrapperMergePolicyFactoryTest, MultiThreadedOCPTest, TestSearchPerf, 
TestBM25SimilarityFactory, DistributedQueryComponentOptimizationTest, 
DOMUtilTest, TestCryptoKeys, PeerSyncReplicationTest, MoreLikeThisHandlerTest, 
TestComplexPhraseQParserPlugin, RollingRestartTest, TestCollectionAPIs, 
SimplePostToolTest, TestExactSharedStatsCache, TestInitQParser, 
SpatialHeatmapFacetsTest, CdcrRequestHandlerTest, 
TestTrackingShardHandlerFactory, TestSizeLimitedDistributedMap, 
TestConfigSetsAPI, TestCloudInspectUtil, TestStandardQParsers, 
TestSolrCloudWithHadoopAuthPlugin, TestSystemCollAutoCreate, 
TestSchemaNameResource, TestDFISimilarityFactory, ExitableDirectoryReaderTest, 
TestBackupRepositoryFactory, TolerantUpdateProcessorTest, 
DirectSolrSpellCheckerTest, TestDistributedGrouping, TestBinaryResponseWriter, 
TestSolrCoreSnapshots, TestManagedResourceStorage, SuggesterFSTTest, 
TestFastLRUCache, TestDistributedStatsComponentCardinality, 
SpellCheckCollatorTest, TestWordDelimiterFilterFactory, 
ConjunctionSolrSpellCheckerTest, ZkCLITest, ChaosMonkeySafeLeaderTest, 
TestAuthorizationFramework, HdfsBasicDistributedZk2Test, 
IndexSchemaRuntimeFieldTest, TestFiltering, ClassificationUpdateProcessorTest, 
SolrIndexMetricsTest, TestSolr4Spatial2, SimpleCollectionCreateDeleteTest, 
IndexSchemaTest, ZkSolrClientTest, SpatialRPTFieldTypeTest, 
DistanceFunctionTest, CleanupOldIndexTest, ShardRoutingTest, SolrXmlInZkTest, 
ChaosMonkeyNothingIsSafeTest, CollectionStateFormat2Test, TestStressRecovery, 
TestSolrCLIRunExample, FileUtilsTest, DistributedDebugComponentTest, 
DistribCursorPagingTest, BlockCacheTest, TestUpdate, 
BigEndianAscendingWordDeserializerTest, TestAddFieldRealTimeGet, 
BlockJoinFacetRandomTest, TestSolrQueryParserResource, TestClassNameShortening, 
SolrCmdDistributorTest, ShardSplitTest, TestReqParamsAPI, TestCharFilters, 
CdcrBootstrapTest, TestGeoJSONResponseWriter, 
SchemaVersionSpecificBehaviorTest, OverriddenZkACLAndCredentialsProvidersTest, 
AtomicUpdatesTest, CollectionsAPISolrJTest, OpenCloseCoreStressTest, 
LeaderElectionIntegrationTest, TestReplicationHandler, TestDistributedSearch]
   [junit4] Completed [348/700 (2!)] on J1 in 31.62s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 52838 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to