[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_121) - Build # 6397 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6397/
Java: 32bit/jdk1.8.0_121 -server -XX:+UseSerialGC

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([322795585C0EAF65:BA73AA82F2F2C29D]: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 11260 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\init-core-data-001
   [junit4]   2> 552011 INFO  
(SUITE-TestDistributedSearch-seed#[322795585C0EAF65]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 552018 INFO  
(SUITE-TestDistributedSearch-seed#[322795585C0EAF65]-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> 552018 INFO  
(SUITE-TestDistributedSearch-seed#[322795585C0EAF65]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 552643 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\control\cores\collection1
   [junit4]   2> 552647 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 552648 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@125192c{/,null,AVAILABLE}
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1d0b492{HTTP/1.1,[http/1.1]}{127.0.0.1:54473}
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] o.e.j.s.Server 
Started @557557ms
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54473, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\control\cores}
   [junit4]   2> 552652 ERROR 
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 552652 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-15T15:44:43.141Z
   [junit4]   2> 552653 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\control\solr.xml
   [junit4]   2> 552795 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\control\cores
   [junit4]   2> 552795 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 552797 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 552873 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 552894 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 552977 WARN  (coreLoadExecutor-1749-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> 552980 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 553001 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 553001 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 553001 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d370d0
   [junit4]   2> 553002 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=305520135, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 553139 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 553139 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 553140 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 553140 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 553141 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 553141 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@19b9ae6[collection1] 
main]
   [junit4]   2> 553141 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 553172 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 553222 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 553222 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 553223 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 553223 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 553223 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 553224 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 553224 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 553224 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 553225 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 553225 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 553227 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@19b9ae6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 553228 INFO  (coreLoadExecutor-1749-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1559414422862036992
   [junit4]   2> 554117 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 554119 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 554120 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2ec386{/,null,AVAILABLE}
   [junit4]   2> 554121 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@dc489a{HTTP/1.1,[http/1.1]}{127.0.0.1:54482}
   [junit4]   2> 554121 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] o.e.j.s.Server 
Started @559027ms
   [junit4]   2> 554121 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54482, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\shard0\cores}
   [junit4]   2> 554121 ERROR 
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 554122 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 554122 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 554122 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 554122 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-02-15T15:44:44.611Z
   [junit4]   2> 554123 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 554262 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\shard0\cores
   [junit4]   2> 554263 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 554378 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 554464 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 554485 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 554609 WARN  (coreLoadExecutor-1760-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> 554612 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 554637 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 554638 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 554638 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1d370d0
   [junit4]   2> 554641 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=305520135, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 554833 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 554833 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 554834 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 554834 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 554838 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 554838 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15bca1b[collection1] 
main]
   [junit4]   2> 554839 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 554851 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 554904 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 554904 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 554905 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 554905 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 554905 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 554905 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 554905 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 554906 INFO  (coreLoadExecutor-1760-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1559414424621547520
   [junit4]   2> 554906 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 554906 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 554908 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 554909 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15bca1b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 555390 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 555398 INFO  (qtp24158334-5923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1559414425131155456)} 0 5
   [junit4]   2> 555404 INFO  (qtp8712072-5947) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1559414425140592640)} 0 3
   [junit4]   2> 555406 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1559414425144786944)]} 0 1
   [junit4]   2> 555408 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1559414425146884096)]} 0 1
   [junit4]   2> 555414 INFO  (qtp24158334-5927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1559414425152126976)]} 0 1
   [junit4]   2> 555415 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1559414425154224128)]} 0 1
   [junit4]   2> 555418 INFO  (qtp24158334-5926) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1559414425156321280)]} 0 2
   [junit4]   2> 555419 INFO  (qtp8712072-5943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1559414425159467008)]} 0 0
   [junit4]   2> 555421 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1559414425160515584)]} 0 0
   [junit4]   2> 555422 INFO  (qtp8712072-5943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1559414425161564160)]} 0 0
   [junit4]   2> 555422 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1559414425162612736)]} 0 0
   [junit4]   2> 555424 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1559414425164709888)]} 0 0
   [junit4]   2> 555425 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1559414425165758464)]} 0 0
   [junit4]   2> 555427 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1559414425167855616)]} 0 0
   [junit4]   2> 555428 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1559414425168904192)]} 0 0
   [junit4]   2> 555429 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1559414425169952768)]} 0 0
   [junit4]   2> 555430 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1559414425169952768)]} 0 0
   [junit4]   2> 555433 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1559414425171001344)]} 0 2
   [junit4]   2> 555434 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1559414425174147072)]} 0 0
   [junit4]   2> 555434 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1559414425175195648)]} 0 0
   [junit4]   2> 555435 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 555435 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@62c32a
   [junit4]   2> 555475 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@b55eda[collection1] main]
   [junit4]   2> 555476 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@b55eda[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 555476 INFO  (qtp24158334-5928) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 555477 INFO  (qtp24158334-5928) [    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 41
   [junit4]   2> 555477 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 555477 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@304e6f
   [junit4]   2> 555518 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ce78a6[collection1] main]
   [junit4]   2> 555520 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ce78a6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 555523 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 555524 INFO  (qtp8712072-5944) [    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 45
   [junit4]   2> 555527 INFO  (qtp24158334-5925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1559414425269567488)]} 0 3
   [junit4]   2> 555532 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1559414425273761792)]} 0 3
   [junit4]   2> 555533 INFO  (qtp24158334-5933) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1559414425277956096)]} 0 0
   [junit4]   2> 555534 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1559414425279004672)]} 0 0
   [junit4]   2> 555535 INFO  (qtp24158334-5933) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1559414425280053248)]} 0 0
   [junit4]   2> 555537 INFO  (qtp8712072-5952) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1559414425282150400)]} 0 1
   [junit4]   2> 555538 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1559414425284247552)]} 0 0
   [junit4]   2> 555539 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1559414425284247552)]} 0 0
   [junit4]   2> 555540 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1559414425285296128)]} 0 0
   [junit4]   2> 555545 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1559414425286344704)]} 0 4
   [junit4]   2> 555545 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1559414425291587584)]} 0 0
   [junit4]   2> 555547 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1559414425293684736)]} 0 0
   [junit4]   2> 555568 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1559414425314656256)]} 0 1
   [junit4]   2> 555569 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1559414425316753408)]} 0 0
   [junit4]   2> 555570 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1559414425317801984)]} 0 0
   [junit4]   2> 555570 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1559414425317801984)]} 0 0
   [junit4]   2> 555572 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1559414425318850560)]} 0 0
   [junit4]   2> 555573 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1559414425320947712)]} 0 0
   [junit4]   2> 555574 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1559414425321996288)]} 0 0
   [junit4]   2> 555574 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1559414425321996288)]} 0 0
   [junit4]   2> 555575 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1559414425323044864)]} 0 0
   [junit4]   2> 555576 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1559414425324093440)]} 0 0
   [junit4]   2> 555576 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1559414425324093440)]} 0 0
   [junit4]   2> 555577 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1559414425325142016)]} 0 0
   [junit4]   2> 555578 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1559414425325142016)]} 0 0
   [junit4]   2> 555578 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1559414425326190592)]} 0 0
   [junit4]   2> 555579 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1559414425327239168)]} 0 0
   [junit4]   2> 555580 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1559414425328287744)]} 0 0
   [junit4]   2> 555581 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1559414425329336320)]} 0 0
   [junit4]   2> 555581 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1559414425329336320)]} 0 0
   [junit4]   2> 555582 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1559414425330384896)]} 0 0
   [junit4]   2> 555583 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1559414425330384896)]} 0 0
   [junit4]   2> 555584 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1559414425332482048)]} 0 0
   [junit4]   2> 555585 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1559414425332482048)]} 0 0
   [junit4]   2> 555585 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1559414425333530624)]} 0 0
   [junit4]   2> 555586 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1559414425334579200)]} 0 0
   [junit4]   2> 555587 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1559414425334579200)]} 0 0
   [junit4]   2> 555587 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1559414425335627776)]} 0 0
   [junit4]   2> 555588 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1559414425336676352)]} 0 0
   [junit4]   2> 555588 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1559414425336676352)]} 0 0
   [junit4]   2> 555589 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1559414425337724928)]} 0 0
   [junit4]   2> 555590 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1559414425338773504)]} 0 0
   [junit4]   2> 555590 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1559414425338773504)]} 0 0
   [junit4]   2> 555591 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1559414425339822080)]} 0 0
   [junit4]   2> 555592 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1559414425340870656)]} 0 0
   [junit4]   2> 555592 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1559414425340870656)]} 0 0
   [junit4]   2> 555593 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1559414425341919232)]} 0 0
   [junit4]   2> 555594 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1559414425342967808)]} 0 0
   [junit4]   2> 555595 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1559414425342967808)]} 0 0
   [junit4]   2> 555595 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1559414425344016384)]} 0 0
   [junit4]   2> 555597 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1559414425346113536)]} 0 0
   [junit4]   2> 555598 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1559414425347162112)]} 0 0
   [junit4]   2> 555598 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1559414425347162112)]} 0 0
   [junit4]   2> 555598 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1559414425347162113)]} 0 0
   [junit4]   2> 555599 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1559414425348210688)]} 0 0
   [junit4]   2> 555599 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1559414425348210688)]} 0 0
   [junit4]   2> 555600 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1559414425349259264)]} 0 0
   [junit4]   2> 555601 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1559414425349259264)]} 0 0
   [junit4]   2> 555601 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1559414425350307840)]} 0 0
   [junit4]   2> 555602 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1559414425351356416)]} 0 0
   [junit4]   2> 555604 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1559414425351356416)]} 0 0
   [junit4]   2> 555604 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1559414425353453568)]} 0 0
   [junit4]   2> 555605 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1559414425353453568)]} 0 0
   [junit4]   2> 555605 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1559414425354502144)]} 0 0
   [junit4]   2> 555606 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1559414425355550720)]} 0 0
   [junit4]   2> 555606 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1559414425355550720)]} 0 0
   [junit4]   2> 555607 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1559414425356599296)]} 0 0
   [junit4]   2> 555608 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1559414425357647872)]} 0 0
   [junit4]   2> 555608 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1559414425357647872)]} 0 0
   [junit4]   2> 555609 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1559414425358696448)]} 0 0
   [junit4]   2> 555610 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1559414425359745024)]} 0 0
   [junit4]   2> 555610 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1559414425359745024)]} 0 0
   [junit4]   2> 555611 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1559414425359745025)]} 0 0
   [junit4]   2> 555611 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1559414425360793600)]} 0 0
   [junit4]   2> 555612 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1559414425361842176)]} 0 0
   [junit4]   2> 555613 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1559414425361842176)]} 0 0
   [junit4]   2> 555613 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1559414425362890752)]} 0 0
   [junit4]   2> 555614 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1559414425363939328)]} 0 0
   [junit4]   2> 555615 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1559414425363939328)]} 0 1
   [junit4]   2> 555617 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1559414425364987904)]} 0 0
   [junit4]   2> 555617 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1559414425367085056)]} 0 0
   [junit4]   2> 555618 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1559414425368133632)]} 0 0
   [junit4]   2> 555619 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1559414425368133632)]} 0 0
   [junit4]   2> 555619 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1559414425369182208)]} 0 0
   [junit4]   2> 555619 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1559414425369182208)]} 0 0
   [junit4]   2> 555620 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1559414425370230784)]} 0 0
   [junit4]   2> 555621 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1559414425370230784)]} 0 0
   [junit4]   2> 555621 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1559414425371279360)]} 0 0
   [junit4]   2> 555622 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1559414425372327936)]} 0 0
   [junit4]   2> 555623 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1559414425373376512)]} 0 0
   [junit4]   2> 555623 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1559414425373376512)]} 0 0
   [junit4]   2> 555624 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1559414425374425088)]} 0 0
   [junit4]   2> 555625 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1559414425375473664)]} 0 0
   [junit4]   2> 555625 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1559414425375473664)]} 0 0
   [junit4]   2> 555626 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1559414425376522240)]} 0 0
   [junit4]   2> 555626 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1559414425376522240)]} 0 0
   [junit4]   2> 555628 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1559414425378619392)]} 0 0
   [junit4]   2> 555629 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1559414425378619392)]} 0 0
   [junit4]   2> 555629 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1559414425379667968)]} 0 0
   [junit4]   2> 555630 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1559414425380716544)]} 0 0
   [junit4]   2> 555631 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1559414425380716544)]} 0 0
   [junit4]   2> 555631 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1559414425381765120)]} 0 0
   [junit4]   2> 555634 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1559414425383862272)]} 0 0
   [junit4]   2> 555634 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1559414425384910848)]} 0 0
   [junit4]   2> 555635 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1559414425385959424)]} 0 0
   [junit4]   2> 555635 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1559414425385959424)]} 0 0
   [junit4]   2> 555637 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1559414425388056576)]} 0 0
   [junit4]   2> 555638 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1559414425388056576)]} 0 0
   [junit4]   2> 555638 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1559414425389105152)]} 0 0
   [junit4]   2> 555638 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1559414425389105152)]} 0 0
   [junit4]   2> 555640 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1559414425391202304)]} 0 0
   [junit4]   2> 555667 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1559414425417416704)]} 0 25
   [junit4]   2> 555668 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1559414425419513856)]} 0 0
   [junit4]   2> 555669 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1559414425420562432)]} 0 0
   [junit4]   2> 555669 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1559414425421611008)]} 0 0
   [junit4]   2> 555670 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1559414425422659584)]} 0 0
   [junit4]   2> 555670 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1559414425422659584)]} 0 0
   [junit4]   2> 555672 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1559414425424756736)]} 0 0
   [junit4]   2> 555672 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 555672 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@62c32a
   [junit4]   2> 555713 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1cb6926[collection1] main]
   [junit4]   2> 555715 INFO  
(searcherExecutor-1750-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1cb6926[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 555715 INFO  (qtp24158334-5929) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 555715 INFO  (qtp24158334-5929) [    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 43
   [junit4]   2> 555715 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 555715 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@304e6f
   [junit4]   2> 555741 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@12b2261[collection1] main]
   [junit4]   2> 555742 INFO  
(searcherExecutor-1761-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@12b2261[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 555743 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 555743 INFO  (qtp8712072-5945) [    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 26
   [junit4]   2> 555744 INFO  (qtp24158334-5926) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 555744 INFO  (qtp24158334-5926) [    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> 555745 INFO  (qtp24158334-5926) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 555745 INFO  (qtp24158334-5926) [    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> 555746 INFO  (qtp24158334-5926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 555752 INFO  (qtp8712072-5953) [    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=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486237&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555754 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486237&ids=1,112,14,146,3,105,138,8,130,131&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555758 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 555760 INFO  (qtp24158334-5926) [    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=1
   [junit4]   2> 555762 INFO  (qtp8712072-5953) [    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:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486250&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555764 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486250&ids=122,12,13,102,116,6,7,106,118,120&isShard=true&shard.url=http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555768 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 555769 INFO  (qtp24158334-5926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 555771 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486259&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555773 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486259&ids=132,143,133,144,2,113,115,137,149,105&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555775 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 555776 INFO  (qtp24158334-5926) [    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> 555778 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1487173486266&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555780 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486266&ids=101,14,124,136,1001,7,108,109,130,131&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555780 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 555781 INFO  (qtp24158334-5926) [    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> 555783 INFO  (qtp8712072-5953) [    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:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486271&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555784 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486271&ids=132,101,113,16,148,105,141,130,120,142&isShard=true&shard.url=http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555785 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555786 INFO  (qtp24158334-5926) [    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> 555787 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486276&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555788 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486276&ids=123,145,146,147,4,138,118,119,140,131&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555789 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:54482//collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555790 INFO  (qtp24158334-5926) [    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> 555792 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:54482//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1487173486280&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555793 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486280&ids=1,134,14,124,4,117,119,109,10,120&isShard=true&shard.url=http://127.0.0.1:54482//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555794 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1|[ff01::213]:33332/&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555794 INFO  (qtp24158334-5926) [    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> 555797 INFO  (qtp8712072-5953) [    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:54482//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1487173486285&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555798 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486285&ids=11,121,2,103,147,136,148,137,6,131&isShard=true&shard.url=http://127.0.0.1:54482//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555798 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555800 INFO  (qtp24158334-5926) [    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> 555801 INFO  (qtp8712072-5953) [    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:54482//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1487173486289&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555802 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486289&ids=121,143,132,122,104,6,106,117,139,108&isShard=true&shard.url=http://127.0.0.1:54482//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555803 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555804 INFO  (qtp24158334-5926) [    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> 555808 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486293&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555809 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486293&ids=134,114,4,125,147,115,105,138,118,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555810 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54482//collection1&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 555811 INFO  (qtp24158334-5926) [    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> 555812 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:54482//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1487173486300&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555813 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486300&ids=114,5,149,1001,119,9,109,140,141,10&isShard=true&shard.url=http://127.0.0.1:54482//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555814 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555815 INFO  (qtp24158334-5926) [    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> 555822 INFO  (qtp8712072-5953) [    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::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486310&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555823 INFO  (qtp8712072-5947) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486310&ids=100,145,102,146,15,136,127,116,128,139&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555825 INFO  (qtp8712072-5945) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:54482//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 555826 INFO  (qtp24158334-5926) [    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> 555828 INFO  (qtp8712072-5950) [    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=http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486316&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555829 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486316&ids=110,143,132,133,147,104,17,139,8,109&isShard=true&shard.url=http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555830 INFO  (qtp8712072-5944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555831 INFO  (qtp24158334-5923) [    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> 555833 INFO  (qtp8712072-5946) [    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:54482//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1487173486321&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555834 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486321&ids=111,2,134,112,136,137,148,107,130,10&isShard=true&shard.url=http://127.0.0.1:54482//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555835 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1|[ff01::114]:33332/&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555835 INFO  (qtp24158334-5923) [    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> 555837 INFO  (qtp8712072-5946) [    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::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1487173486326&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555838 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486326&ids=121,145,3,104,16,105,127,7,129,131&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555839 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:54482//collection1|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555839 INFO  (qtp24158334-5923) [    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> 555841 INFO  (qtp8712072-5946) [    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:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486329&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555850 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486329&ids=11,1,12,13,115,126,5,106,9,140&isShard=true&shard.url=http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555851 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 555852 INFO  (qtp24158334-5923) [    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> 555853 INFO  (qtp8712072-5946) [    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=[ff01::083]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1487173486342&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555861 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486342&ids=143,110,111,133,2,102,124,9,10,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555862 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 555863 INFO  (qtp24158334-5923) [    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> 555864 INFO  (qtp8712072-5946) [    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:54482//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1487173486352&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555865 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486352&ids=14,136,147,16,138,1001,106,128,129,142&isShard=true&shard.url=http://127.0.0.1:54482//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555866 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:54482//collection1|[ff01::083]:33332/&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555867 INFO  (qtp24158334-5923) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 555870 INFO  (qtp8712072-5946) [    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_i_ni_p+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486357&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555871 INFO  (qtp8712072-5946) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486357&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555872 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54482//collection1&sort=a_i_ni_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 555881 INFO  (qtp24158334-5923) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 555884 INFO  (qtp8712072-5954) [    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_i_ni_p,5))+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1487173486372&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555885 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486372&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555886 INFO  (qtp8712072-5950) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 555894 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_ni_p[*+TO+*]&distrib=false&sort=a_i_ni_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=5
   [junit4]   2> 555899 INFO  (qtp8712072-5947) [    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_i_ni_p+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::083]:33332/&rows=10&version=2&q=a_i_ni_p[*+TO+*]&NOW=1487173486385&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 555900 INFO  (qtp8712072-5947) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_ni_p[*+TO+*]&distrib=false&shards.purpose=64&NOW=1487173486385&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555901 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_ni_p[*+TO+*]&shards=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::083]:33332/&sort=a_i_ni_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=4
   [junit4]   2> 555904 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=2
   [junit4]   2> 555906 INFO  (qtp8712072-5947) [    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_i_ni_p+asc,+id+desc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:54482//collection1&rows=10&version=2&q=*:*&NOW=1487173486394&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555906 INFO  (qtp8712072-5947) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1487173486394&ids=11,144,145,146,147,4,148,149,6,1001&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:54482//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555907 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:54482//collection1&sort=a_i_ni_p+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 555908 INFO  (qtp24158334-5930) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i_ni_p+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 555910 INFO  (qtp8712072-5947) [    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_i_ni_p+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1487173486399&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555914 INFO  (qtp8712072-5947) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1487173486399&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555915 INFO  (qtp8712072-5953) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/&fl=*,score&sort=a_i_ni_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 555917 INFO  (qtp24158334-5930) [    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> 555918 INFO  (qtp8712072-5947) [    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:54482//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1487173486406&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 555919 INFO  (qtp8712072-5947) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1487173486406&ids=111,2,134,112,136,137,148,107,130,10&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:54482//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 555920 INFO  (qt

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

eAllowed=1&wt=javabin&group=true} status=0 QTime=5
   [junit4]   2> 583988 INFO  (qtp30161180-6016) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={fl=id,a_i_ni_p&group.limit=10&sort=a_i_ni_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=http://127.0.0.1:54522//collection1,[ff01::114]:33332/|http://127.0.0.1:54531//collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=11
   [junit4]   2> 583988 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 583990 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@af0d0f{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 583990 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=32493349
   [junit4]   2> 583993 INFO  (coreCloseExecutor-1803-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1ccc90e
   [junit4]   2> 584317 INFO  (coreCloseExecutor-1803-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 584318 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 584318 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1aa06c8{/,null,UNAVAILABLE}
   [junit4]   2> 584322 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@d82d34{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 584322 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=25625124
   [junit4]   2> 584323 INFO  (coreCloseExecutor-1805-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@a95f1
   [junit4]   2> 584369 INFO  (coreCloseExecutor-1805-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 584371 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 584371 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@116d377{/,null,UNAVAILABLE}
   [junit4]   2> 584372 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@1e85278{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 584373 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2883849
   [junit4]   2> 584374 INFO  (coreCloseExecutor-1807-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@113a774
   [junit4]   2> 584411 INFO  (coreCloseExecutor-1807-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 584413 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 584414 INFO  
(TEST-TestDistributedSearch.test-seed#[322795585C0EAF65]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@42fc58{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=322795585C0EAF65 -Dtests.slow=true -Dtests.locale=uk 
-Dtests.timezone=America/Creston -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 32.4s 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([322795585C0EAF65:BA73AA82F2F2C29D]: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> 584422 INFO  
(SUITE-TestDistributedSearch-seed#[322795585C0EAF65]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_322795585C0EAF65-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{other_tl1=PostingsFormat(name=Memory), 
range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
SubjectTerms_mfacet=PostingsFormat(name=Memory), 
n_l1=PostingsFormat(name=Direct), a_n_tdt=Lucene50(blocksize=128), 
multiDefault=PostingsFormat(name=Direct), 
intDefault=PostingsFormat(name=Memory), n_td1=PostingsFormat(name=Memory), 
n_d1=PostingsFormat(name=Direct), n_f1=PostingsFormat(name=Memory), 
range_facet_l=PostingsFormat(name=Direct), n_tl1=PostingsFormat(name=Memory), 
a_s=Lucene50(blocksize=128), 
oddField_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
n_tf1=PostingsFormat(name=Direct), lowerfilt1and2=PostingsFormat(name=Direct), 
a_t=PostingsFormat(name=Direct), lowerfilt=PostingsFormat(name=Direct), 
id=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
text=Lucene50(blocksize=128), timestamp=PostingsFormat(name=Direct), 
severity=Lucene50(blocksize=128), foo_sev_enum=Lucene50(blocksize=128), 
n_dt1=PostingsFormat(name=Memory), b_n_tdt=PostingsFormat(name=Memory), 
n_ti1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
rnd_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
foo_b=Lucene50(blocksize=128), n_tdt1=PostingsFormat(name=Memory), 
foo_d=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
range_facet_i_dv=PostingsFormat(name=Direct), foo_f=Lucene50(blocksize=128)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), 
foo_sev_enum=DocValuesFormat(name=Memory), 
_version_=DocValuesFormat(name=Asserting), 
range_facet_i_dv=DocValuesFormat(name=Lucene70), 
intDvoDefault=DocValuesFormat(name=Lucene70), 
a_i_ni_p=DocValuesFormat(name=Lucene70), 
timestamp=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1069, 
maxMBSortInHeap=5.892692130785112, sim=RandomSimilarity(queryNorm=false): {}, 
locale=uk, timezone=America/Creston
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_121 
(32-bit)/cpus=3,threads=1,free=149764176,total=295473152
   [junit4]   2> NOTE: All tests run in this JVM: 
[CollectionTooManyReplicasTest, ZkNodePropsTest, TestCollationFieldDocValues, 
TestStressLucene, CreateCollectionCleanupTest, QueryElevationComponentTest, 
TestCharFilters, DistributedFacetPivotLargeTest, CheckHdfsIndexTest, 
DistributedMLTComponentTest, ReplicaListTransformerTest, 
LeaderFailureAfterFreshStartTest, TestSolrCloudWithKerberosAlt, 
TestStressLiveNodes, BinaryUpdateRequestHandlerTest, SolrXmlInZkTest, 
TestNumericTerms64, ZkStateReaderTest, TestShardHandlerFactory, 
HLLSerializationTest, SolrMetricsIntegrationTest, TestHighlightDedupGrouping, 
SimpleMLTQParserTest, MissingSegmentRecoveryTest, BasicDistributedZkTest, 
HdfsChaosMonkeyNothingIsSafeTest, TestBulkSchemaConcurrent, TestXmlQParser, 
TestMissingGroups, TestRandomDVFaceting, TermsComponentTest, SOLR749Test, 
TestDefaultSearchFieldResource, TestDistribDocBasedVersion, 
CdcrReplicationHandlerTest, CleanupOldIndexTest, TestCSVResponseWriter, 
TestSolrFieldCacheMBean, SyncSliceTest, SolrCoreTest, 
AsyncCallRequestStatusResponseTest, TestSlowCompositeReaderWrapper, 
TestHashPartitioner, MergeStrategyTest, SolrGraphiteReporterTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestExtendedDismaxParser, 
TestDistributedSearch]
   [junit4] Completed [128/694 (1!)] on J0 in 32.44s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 56630 lines...]


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