[JENKINS] Lucene-Solr-master-Solaris (64bit/jdk1.8.0) - Build # 1159 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1159/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC

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

Error Message:
Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: No live SolrServers 
available to handle this 
request,trace=org.apache.solr.client.solrj.SolrServerException: No live 
SolrServers available to handle this request  at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
  at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:259)
  at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:166)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
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.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
 at java.lang.Thread.run(Thread.java:745) ,time=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.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
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.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
,time=1}
        at 
__randomizedtesting.SeedInfo.seed([2BBA129E318CD928:A3EE2D449F70B4D0]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11054 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/init-core-data-001
   [junit4]   2> 277154 INFO  
(SUITE-TestDistributedSearch-seed#[2BBA129E318CD928]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 277155 INFO  
(SUITE-TestDistributedSearch-seed#[2BBA129E318CD928]-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> 277155 INFO  
(SUITE-TestDistributedSearch-seed#[2BBA129E318CD928]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 277334 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/control/cores/collection1
   [junit4]   2> 277336 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 277337 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@47bea6d4{/,null,AVAILABLE}
   [junit4]   2> 277339 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@4b9c5e5b{HTTP/1.1,[http/1.1]}{127.0.0.1:52203}
   [junit4]   2> 277339 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] o.e.j.s.Server 
Started @280208ms
   [junit4]   2> 277339 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=52203, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/control/cores}
   [junit4]   2> 277340 ERROR 
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 277340 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 277340 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 277340 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 277340 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-27T13:53:01.952Z
   [junit4]   2> 277340 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/control/solr.xml
   [junit4]   2> 277407 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/control/cores
   [junit4]   2> 277408 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 277409 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 277441 WARN  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 277442 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 277466 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 277542 WARN  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 277545 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 277552 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
   [junit4]   2> 277553 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 277553 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d57e76
   [junit4]   2> 277554 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=25, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=85.740234375, 
floorSegmentMB=0.751953125, forceMergeDeletesPctAllowed=10.413096376216899, 
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.32444041596675544
   [junit4]   2> 277608 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 277608 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 277609 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 277609 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 277610 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=2096278818, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 277611 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@503af42b[collection1] main]
   [junit4]   2> 277611 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 277615 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 277630 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 277631 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 277631 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 277631 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 277631 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 277632 INFO  (coreLoadExecutor-1089-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1560494559455084544
   [junit4]   2> 277632 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 277632 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 277632 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 277633 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 277633 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 277634 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@503af42b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 278195 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 278197 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 278198 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@31674973{/,null,AVAILABLE}
   [junit4]   2> 278199 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2b97a7d{HTTP/1.1,[http/1.1]}{127.0.0.1:60608}
   [junit4]   2> 278199 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] o.e.j.s.Server 
Started @281069ms
   [junit4]   2> 278199 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=60608, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/shard0/cores}
   [junit4]   2> 278199 ERROR 
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 278218 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 278218 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 278218 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 278218 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-27T13:53:02.830Z
   [junit4]   2> 278219 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 278268 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/shard0/cores
   [junit4]   2> 278268 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 278303 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 278475 WARN  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 278476 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 278503 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 278591 WARN  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 278593 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 278600 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
   [junit4]   2> 278600 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 278600 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@63d57e76
   [junit4]   2> 278601 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=25, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=85.740234375, 
floorSegmentMB=0.751953125, forceMergeDeletesPctAllowed=10.413096376216899, 
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.32444041596675544
   [junit4]   2> 278708 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 278708 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 278709 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 278709 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 278721 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=2096278818, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 278721 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@130f670b[collection1] main]
   [junit4]   2> 278722 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 278728 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 278747 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 278747 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 278747 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 278747 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 278747 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 278748 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 278748 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 278748 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 278749 INFO  (coreLoadExecutor-1100-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1560494560626343936
   [junit4]   2> 278749 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 278749 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 278750 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@130f670b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 279095 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 279101 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560494560993345536)} 0 1
   [junit4]   2> 279108 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560494561000685568)} 0 2
   [junit4]   2> 279116 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1560494561004879872)]} 0 7
   [junit4]   2> 279128 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1560494561017462784)]} 0 6
   [junit4]   2> 279130 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1560494561024802816)]} 0 1
   [junit4]   2> 279132 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1560494561027948544)]} 0 1
   [junit4]   2> 279134 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1560494561028997120)]} 0 0
   [junit4]   2> 279136 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1560494561031094272)]} 0 0
   [junit4]   2> 279137 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1560494561033191424)]} 0 0
   [junit4]   2> 279140 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1560494561035288576)]} 0 0
   [junit4]   2> 279141 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1560494561036337152)]} 0 0
   [junit4]   2> 279149 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1560494561038434304)]} 0 7
   [junit4]   2> 279152 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1560494561047871488)]} 0 0
   [junit4]   2> 279155 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1560494561051017216)]} 0 1
   [junit4]   2> 279156 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1560494561053114368)]} 0 0
   [junit4]   2> 279158 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1560494561054162944)]} 0 0
   [junit4]   2> 279159 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1560494561056260096)]} 0 0
   [junit4]   2> 279161 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1560494561057308672)]} 0 0
   [junit4]   2> 279162 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1560494561058357248)]} 0 1
   [junit4]   2> 279164 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1560494561060454400)]} 0 0
   [junit4]   2> 279164 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 279164 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13758ade
   [junit4]   2> 279175 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3fea7766[collection1] main]
   [junit4]   2> 279176 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 279177 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3fea7766[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 279178 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 13
   [junit4]   2> 279179 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 279179 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@628637f5
   [junit4]   2> 279189 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d79c24d[collection1] main]
   [junit4]   2> 279189 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 279191 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d79c24d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 279192 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 279195 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1560494561091911680)]} 0 2
   [junit4]   2> 279214 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1560494561099251712)]} 0 14
   [junit4]   2> 279216 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1560494561114980352)]} 0 0
   [junit4]   2> 279217 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1560494561117077504)]} 0 0
   [junit4]   2> 279218 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1560494561118126080)]} 0 0
   [junit4]   2> 279219 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1560494561119174656)]} 0 0
   [junit4]   2> 279221 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1560494561120223232)]} 0 0
   [junit4]   2> 279223 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1560494561122320384)]} 0 1
   [junit4]   2> 279225 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1560494561124417536)]} 0 1
   [junit4]   2> 279227 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1560494561126514688)]} 0 1
   [junit4]   2> 279229 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1560494561128611840)]} 0 0
   [junit4]   2> 279230 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1560494561129660416)]} 0 0
   [junit4]   2> 279231 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1560494561130708992)]} 0 0
   [junit4]   2> 279232 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1560494561132806144)]} 0 0
   [junit4]   2> 279233 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1560494561133854720)]} 0 0
   [junit4]   2> 279235 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1560494561135951872)]} 0 0
   [junit4]   2> 279237 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1560494561137000448)]} 0 0
   [junit4]   2> 279238 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1560494561138049024)]} 0 0
   [junit4]   2> 279239 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1560494561140146176)]} 0 0
   [junit4]   2> 279240 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1560494561141194752)]} 0 0
   [junit4]   2> 279241 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1560494561142243328)]} 0 0
   [junit4]   2> 279243 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1560494561143291904)]} 0 0
   [junit4]   2> 279244 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1560494561145389056)]} 0 0
   [junit4]   2> 279245 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1560494561146437632)]} 0 0
   [junit4]   2> 279247 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1560494561148534784)]} 0 1
   [junit4]   2> 279249 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1560494561149583360)]} 0 0
   [junit4]   2> 279250 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1560494561151680512)]} 0 0
   [junit4]   2> 279251 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1560494561152729088)]} 0 0
   [junit4]   2> 279253 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1560494561154826240)]} 0 0
   [junit4]   2> 279256 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1560494561157971968)]} 0 1
   [junit4]   2> 279258 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1560494561159020544)]} 0 0
   [junit4]   2> 279259 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1560494561161117696)]} 0 0
   [junit4]   2> 279260 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1560494561162166272)]} 0 0
   [junit4]   2> 279262 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1560494561163214848)]} 0 0
   [junit4]   2> 279263 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1560494561165312000)]} 0 0
   [junit4]   2> 279264 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1560494561166360576)]} 0 0
   [junit4]   2> 279266 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1560494561167409152)]} 0 0
   [junit4]   2> 279267 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1560494561169506304)]} 0 0
   [junit4]   2> 279269 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1560494561170554880)]} 0 0
   [junit4]   2> 279270 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1560494561172652032)]} 0 0
   [junit4]   2> 279272 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1560494561173700608)]} 0 0
   [junit4]   2> 279273 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1560494561175797760)]} 0 0
   [junit4]   2> 279275 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1560494561176846336)]} 0 0
   [junit4]   2> 279276 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1560494561178943488)]} 0 0
   [junit4]   2> 279278 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1560494561179992064)]} 0 0
   [junit4]   2> 279279 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1560494561182089216)]} 0 0
   [junit4]   2> 279280 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1560494561183137792)]} 0 0
   [junit4]   2> 279282 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1560494561185234944)]} 0 0
   [junit4]   2> 279285 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1560494561187332096)]} 0 0
   [junit4]   2> 279287 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1560494561189429248)]} 0 0
   [junit4]   2> 279288 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1560494561191526400)]} 0 0
   [junit4]   2> 279290 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1560494561192574976)]} 0 0
   [junit4]   2> 279291 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1560494561194672128)]} 0 0
   [junit4]   2> 279293 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1560494561195720704)]} 0 0
   [junit4]   2> 279295 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1560494561197817856)]} 0 0
   [junit4]   2> 279297 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1560494561199915008)]} 0 0
   [junit4]   2> 279299 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1560494561202012160)]} 0 0
   [junit4]   2> 279300 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1560494561203060736)]} 0 0
   [junit4]   2> 279301 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1560494561205157888)]} 0 0
   [junit4]   2> 279302 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1560494561206206464)]} 0 0
   [junit4]   2> 279303 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1560494561207255040)]} 0 0
   [junit4]   2> 279308 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1560494561211449344)]} 0 0
   [junit4]   2> 279309 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1560494561213546496)]} 0 0
   [junit4]   2> 279311 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1560494561215643648)]} 0 0
   [junit4]   2> 279315 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1560494561219837952)]} 0 0
   [junit4]   2> 279317 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1560494561221935104)]} 0 0
   [junit4]   2> 279319 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1560494561224032256)]} 0 0
   [junit4]   2> 279321 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1560494561225080832)]} 0 0
   [junit4]   2> 279322 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1560494561227177984)]} 0 0
   [junit4]   2> 279324 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1560494561229275136)]} 0 0
   [junit4]   2> 279326 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1560494561231372288)]} 0 0
   [junit4]   2> 279328 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1560494561233469440)]} 0 0
   [junit4]   2> 279330 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1560494561234518016)]} 0 0
   [junit4]   2> 279331 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1560494561236615168)]} 0 0
   [junit4]   2> 279333 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1560494561237663744)]} 0 1
   [junit4]   2> 279335 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1560494561239760896)]} 0 0
   [junit4]   2> 279336 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1560494561240809472)]} 0 0
   [junit4]   2> 279337 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1560494561242906624)]} 0 0
   [junit4]   2> 279338 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1560494561243955200)]} 0 0
   [junit4]   2> 279340 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1560494561246052352)]} 0 0
   [junit4]   2> 279341 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1560494561247100928)]} 0 0
   [junit4]   2> 279343 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1560494561249198080)]} 0 0
   [junit4]   2> 279344 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1560494561250246656)]} 0 0
   [junit4]   2> 279345 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1560494561251295232)]} 0 0
   [junit4]   2> 279347 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1560494561252343808)]} 0 0
   [junit4]   2> 279348 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1560494561253392384)]} 0 0
   [junit4]   2> 279349 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1560494561255489536)]} 0 0
   [junit4]   2> 279351 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1560494561256538112)]} 0 0
   [junit4]   2> 279352 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1560494561257586688)]} 0 0
   [junit4]   2> 279353 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1560494561259683840)]} 0 0
   [junit4]   2> 279355 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1560494561260732416)]} 0 0
   [junit4]   2> 279356 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1560494561262829568)]} 0 0
   [junit4]   2> 279358 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1560494561263878144)]} 0 0
   [junit4]   2> 279359 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1560494561264926720)]} 0 0
   [junit4]   2> 279360 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1560494561267023872)]} 0 0
   [junit4]   2> 279361 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1560494561268072448)]} 0 0
   [junit4]   2> 279363 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1560494561269121024)]} 0 0
   [junit4]   2> 279364 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1560494561271218176)]} 0 0
   [junit4]   2> 279366 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1560494561272266752)]} 0 0
   [junit4]   2> 279368 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1560494561274363904)]} 0 0
   [junit4]   2> 279369 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1560494561276461056)]} 0 0
   [junit4]   2> 279370 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1560494561277509632)]} 0 0
   [junit4]   2> 279372 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1560494561278558208)]} 0 0
   [junit4]   2> 279373 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1560494561280655360)]} 0 0
   [junit4]   2> 279374 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1560494561281703936)]} 0 0
   [junit4]   2> 279376 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1560494561282752512)]} 0 0
   [junit4]   2> 279378 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1560494561284849664)]} 0 0
   [junit4]   2> 279379 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1560494561286946816)]} 0 0
   [junit4]   2> 279381 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1560494561289043968)]} 0 0
   [junit4]   2> 279383 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1560494561290092544)]} 0 0
   [junit4]   2> 279384 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1560494561292189696)]} 0 0
   [junit4]   2> 279386 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1560494561294286848)]} 0 0
   [junit4]   2> 279389 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1560494561296384000)]} 0 0
   [junit4]   2> 279390 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1560494561298481152)]} 0 0
   [junit4]   2> 279392 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1560494561300578304)]} 0 0
   [junit4]   2> 279393 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1560494561301626880)]} 0 0
   [junit4]   2> 279394 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1560494561302675456)]} 0 0
   [junit4]   2> 279395 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1560494561303724032)]} 0 0
   [junit4]   2> 279396 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 279397 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13758ade
   [junit4]   2> 279418 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@46490893[collection1] main]
   [junit4]   2> 279418 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 279420 INFO  
(searcherExecutor-1090-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@46490893[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 279421 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 24
   [junit4]   2> 279422 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 279423 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@628637f5
   [junit4]   2> 279442 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@74cb9ddd[collection1] main]
   [junit4]   2> 279443 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 279444 INFO  
(searcherExecutor-1101-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@74cb9ddd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 279445 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 23
   [junit4]   2> 279447 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 279447 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 279449 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 279449 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 279451 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 279458 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584065&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279463 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584065&ids=11,12,123,13,126,149,138,127,139,120&isShard=true&shard.url=http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=2
   [junit4]   2> 279470 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=16
   [junit4]   2> 279472 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279476 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:60608//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1488203584086&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279492 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584086&ids=100,145,112,3,148,105,118,129,10,131&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=6
   [junit4]   2> 279520 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1|[ff01::083]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=45
   [junit4]   2> 279524 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=2
   [junit4]   2> 279573 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488203584164&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279577 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584164&ids=11,111,134,113,147,126,116,138,141,10&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 279583 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=30
   [junit4]   2> 279588 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279593 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584204&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279596 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584204&ids=110,144,123,135,15,103,5,16,117,119&isShard=true&shard.url=http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279603 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=11
   [junit4]   2> 279605 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279609 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488203584219&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279613 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584219&ids=110,1,134,136,147,6,117,129,108,142&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 279616 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1|[ff01::114]:33332/&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 279618 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279622 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:60608//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488203584233&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279628 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584233&ids=11,132,3,103,114,138,116,17,9,140&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=3
   [junit4]   2> 279630 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 279633 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279644 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584247&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279647 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584247&ids=143,132,122,111,100,136,104,116,139,128&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279648 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:60608//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 279650 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279654 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1488203584264&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279657 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584264&ids=110,133,123,126,6,17,106,118,129,109&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 279660 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 279662 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279666 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488203584276&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279670 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584276&ids=143,122,2,16,148,126,5,149,7,9&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 279681 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1|[ff01::114]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=16
   [junit4]   2> 279684 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279687 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488203584297&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279711 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584297&ids=12,111,145,101,103,106,108,140,130,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279725 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=39
   [junit4]   2> 279728 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279732 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1488203584342&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279734 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584342&ids=121,134,14,103,16,115,116,108,140,120&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279735 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 279737 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279741 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1488203584351&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279744 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584351&ids=143,132,12,3,139,7,118,109,10,142&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279745 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 279747 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279752 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584361&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279755 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584361&ids=110,123,15,126,16,5,149,106,1001,131&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279756 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60608//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 279759 INFO  (qtp488751883-2318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279763 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584372&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 279766 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584372&ids=144,124,115,127,138,117,107,119,9,142&isShard=true&shard.url=http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279768 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 279770 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279774 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1488203584384&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279777 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584384&ids=2,102,135,15,104,137,126,105,129,109&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279779 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:60608//collection1|[ff01::083]:33332/&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 279780 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279784 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584394&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279787 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584394&ids=132,144,1,134,101,13,136,148,106,9&isShard=true&shard.url=http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 279788 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 279790 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279794 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584404&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279797 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584404&ids=132,121,134,3,113,135,125,104,16,117&isShard=true&shard.url=http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279798 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 279801 INFO  (qtp488751883-2316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 279804 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:60608//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1488203584415&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279807 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584415&ids=11,133,101,124,147,5,149,9,109,120&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279809 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1|[ff01::083]:33332/&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 279812 INFO  (qtp488751883-2314) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 279816 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488203584426&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279819 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584426&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279820 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:60608//collection1|[ff01::114]:33332/&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 279824 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 279828 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584438&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279831 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584438&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279832 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60608//collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 279847 INFO  (qtp488751883-2317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i1[*+TO+*]&distrib=false&sort=a_i1+asc&wt=javabin&version=2} 
hits=13 status=0 QTime=12
   [junit4]   2> 279852 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=a_i1[*+TO+*]&NOW=1488203584461&isShard=true&wt=javabin}
 hits=13 status=0 QTime=1
   [junit4]   2> 279861 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i1[*+TO+*]&distrib=false&shards.purpose=64&NOW=1488203584461&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:60608//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279862 INFO  (qtp477635813-2331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i1[*+TO+*]&shards=http://127.0.0.1:60608//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=a_i1+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=13
   [junit4]   2> 279865 INFO  (qtp488751883-2321) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 279869 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc,+id+desc&shard.url=http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584479&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279872 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488203584479&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279873 INFO  (qtp477635813-2338) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:60608//collection1&sort=a_i1+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 279877 INFO  (qtp488751883-2320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 279881 INFO  (qtp477635813-2335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584491&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279884 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488203584491&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279885 INFO  (qtp477635813-2332) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60608//collection1&fl=*,score&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 279888 INFO  (qtp488751883-2319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 279891 INFO  (qtp477635813-2333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:60608//collection1&rows=10&version=2&q=*:*&NOW=1488203584501&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 279893 INFO  (qtp477635813-2336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488203584501&ids=144,124,115,127,138,117,107,119,9,142&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:60608//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 279895 INFO  (qtp477635813-2334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/sel

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

=1488203651346&ids=13&isShard=true&timeAllowed=1&wt=javabin} status=0 QTime=0
   [junit4]   2> 346738 INFO  (qtp215821967-2453) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:39528//collection1,http://127.0.0.1:43140//collection1,[ff01::213]:33332/|http://127.0.0.1:42676//collection1|[ff01::114]:33332/|[ff01::083]:33332/&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=4
   [junit4]   2> 346738 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 346743 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@548e889e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 346743 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1222609755
   [junit4]   2> 346745 INFO  (coreCloseExecutor-1195-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5f781039
   [junit4]   2> 346793 INFO  (coreCloseExecutor-1195-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 346794 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 346796 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@9c0ca3d{/,null,UNAVAILABLE}
   [junit4]   2> 346799 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@8917007{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 346799 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1641117739
   [junit4]   2> 346805 INFO  (coreCloseExecutor-1197-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7bdb3618
   [junit4]   2> 346837 INFO  (coreCloseExecutor-1197-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 346840 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 346842 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1e788708{/,null,UNAVAILABLE}
   [junit4]   2> 346846 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@2350b8d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 346846 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=560091297
   [junit4]   2> 346850 INFO  (coreCloseExecutor-1199-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5ba3a88e
   [junit4]   2> 346880 INFO  (coreCloseExecutor-1199-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 346882 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 346884 INFO  
(TEST-TestDistributedSearch.test-seed#[2BBA129E318CD928]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2b3bd251{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=2BBA129E318CD928 -Dtests.slow=true -Dtests.locale=he 
-Dtests.timezone=Asia/Famagusta -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 69.7s J0 | 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.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [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.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    > ,time=1}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([2BBA129E318CD928:A3EE2D449F70B4D0]: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 
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.lang.Thread.run(Thread.java:745)
   [junit4]   2> 346894 INFO  
(SUITE-TestDistributedSearch-seed#[2BBA129E318CD928]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_2BBA129E318CD928-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{other_tl1=PostingsFormat(name=LuceneFixedGap), 
range_facet_l_dv=PostingsFormat(name=LuceneVarGapFixedInterval), 
SubjectTerms_mfacet=PostingsFormat(name=LuceneFixedGap), 
n_l1=PostingsFormat(name=LuceneFixedGap), 
a_n_tdt=PostingsFormat(name=LuceneVarGapFixedInterval), 
multiDefault=Lucene50(blocksize=128), 
intDefault=PostingsFormat(name=LuceneFixedGap), n_td1=Lucene50(blocksize=128), 
n_d1=PostingsFormat(name=LuceneFixedGap), n_f1=Lucene50(blocksize=128), 
range_facet_l=PostingsFormat(name=LuceneFixedGap), 
n_tl1=Lucene50(blocksize=128), 
a_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
oddField_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_tf1=PostingsFormat(name=LuceneFixedGap), 
lowerfilt1and2=Lucene50(blocksize=128), 
a_t=PostingsFormat(name=LuceneFixedGap), lowerfilt=Lucene50(blocksize=128), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), 
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 timestamp=PostingsFormat(name=LuceneFixedGap), 
severity=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 foo_sev_enum=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_dt1=Lucene50(blocksize=128), a_i1=PostingsFormat(name=LuceneFixedGap), 
b_n_tdt=PostingsFormat(name=LuceneFixedGap), 
n_ti1=PostingsFormat(name=LuceneVarGapFixedInterval), 
rnd_b=PostingsFormat(name=LuceneVarGapFixedInterval), 
foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_tdt1=PostingsFormat(name=LuceneFixedGap), 
foo_d=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_i_dv=PostingsFormat(name=LuceneFixedGap), 
foo_f=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
 docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), 
foo_sev_enum=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Lucene70), 
range_facet_i_dv=DocValuesFormat(name=Lucene70), 
intDvoDefault=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=985, 
maxMBSortInHeap=7.98931040323774, sim=RandomSimilarity(queryNorm=true): {}, 
locale=he, timezone=Asia/Famagusta
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=2,free=161668864,total=377487360
   [junit4]   2> NOTE: All tests run in this JVM: [DocValuesTest, 
DistributedQueryElevationComponentTest, EnumFieldTest, TestDocumentBuilder, 
SolrMetricsIntegrationTest, TestRandomRequestDistribution, 
TestConfigSetsAPIExclusivity, PrimitiveFieldTypeTest, 
TestUseDocValuesAsStored2, TestCharFilters, HttpSolrCallGetCoreTest, 
TestChildDocTransformer, JSONWriterTest, QueryEqualityTest, 
TestClassicSimilarityFactory, PluginInfoTest, TestSolrConfigHandlerConcurrent, 
TestTrackingShardHandlerFactory, SolrCoreTest, DistributedExpandComponentTest, 
TestStressReorder, CursorPagingTest, SSLMigrationTest, 
TestCollationFieldDocValues, TestBulkSchemaConcurrent, TestCloudRecovery, 
TestRemoteStreaming, TestDistributedSearch]
   [junit4] Completed [84/693 (1!)] on J0 in 69.76s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 64629 lines...]


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