Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1441/

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:226)
  at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:198)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
  at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
 at java.lang.Thread.run(Thread.java:745) ,time=2}

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: No live SolrServers 
available to handle this 
request,trace=org.apache.solr.client.solrj.SolrServerException: No live 
SolrServers available to handle this request
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:226)
        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:198)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
,time=2}
        at 
__randomizedtesting.SeedInfo.seed([AE8E775EE7EF5666:26DA488449133B9E]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1172)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1113)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:973)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        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:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11512 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/init-core-data-001
   [junit4]   2> 859403 INFO  
(SUITE-TestDistributedSearch-seed#[AE8E775EE7EF5666]-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> 859403 INFO  
(SUITE-TestDistributedSearch-seed#[AE8E775EE7EF5666]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /s_f/
   [junit4]   2> 859476 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/control/cores/collection1
   [junit4]   2> 859477 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 859478 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2e46f2c1{/s_f,null,AVAILABLE}
   [junit4]   2> 859481 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@3d68539c{HTTP/1.1,[http/1.1]}{127.0.0.1:39907}
   [junit4]   2> 859481 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] o.e.j.s.Server 
Started @861802ms
   [junit4]   2> 859481 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/s_f, 
hostPort=39907, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/control/cores}
   [junit4]   2> 859481 ERROR 
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 859481 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 859481 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 859482 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 859482 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-22T21:54:06.705Z
   [junit4]   2> 859482 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/control/solr.xml
   [junit4]   2> 859491 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/control/cores
   [junit4]   2> 859491 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 859492 INFO  (coreLoadExecutor-3958-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 859512 INFO  (coreLoadExecutor-3958-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 859529 INFO  (coreLoadExecutor-3958-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 859598 WARN  (coreLoadExecutor-3958-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 859600 INFO  (coreLoadExecutor-3958-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 859604 INFO  (coreLoadExecutor-3958-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 859605 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 859605 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c503f5
   [junit4]   2> 859605 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3195292599962961]
   [junit4]   2> 859614 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 859614 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 859614 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 859614 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 859614 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=16, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=10.162109375, 
floorSegmentMB=0.775390625, forceMergeDeletesPctAllowed=27.14163128807044, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7368936200300011
   [junit4]   2> 859614 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6095d93c[collection1] main]
   [junit4]   2> 859615 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 859620 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 859638 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 859638 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 859638 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 859638 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 859638 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 859638 INFO  (coreLoadExecutor-3958-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1548928414500519936
   [junit4]   2> 859638 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 859639 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 859639 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 859639 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 859639 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 859640 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6095d93c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 860058 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 860059 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 860060 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1244b69c{/s_f,null,AVAILABLE}
   [junit4]   2> 860060 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@60e21c92{HTTP/1.1,[http/1.1]}{127.0.0.1:50753}
   [junit4]   2> 860061 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] o.e.j.s.Server 
Started @862382ms
   [junit4]   2> 860061 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/s_f, 
hostPort=50753, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/shard0/cores}
   [junit4]   2> 860061 ERROR 
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 860061 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 860061 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 860061 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 860061 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-22T21:54:07.284Z
   [junit4]   2> 860062 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 860067 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/shard0/cores
   [junit4]   2> 860067 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 860068 INFO  (coreLoadExecutor-3969-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 860096 INFO  (coreLoadExecutor-3969-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 860113 INFO  (coreLoadExecutor-3969-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 860177 WARN  (coreLoadExecutor-3969-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 860178 INFO  (coreLoadExecutor-3969-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 860183 INFO  (coreLoadExecutor-3969-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1
   [junit4]   2> 860183 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 860183 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c503f5
   [junit4]   2> 860184 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=30, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3195292599962961]
   [junit4]   2> 860194 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 860194 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 860194 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 860194 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 860195 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=16, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=10.162109375, 
floorSegmentMB=0.775390625, forceMergeDeletesPctAllowed=27.14163128807044, 
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7368936200300011
   [junit4]   2> 860195 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1bd74216[collection1] main]
   [junit4]   2> 860195 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 860200 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 860218 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 860218 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 860218 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 860218 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 860218 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 860218 INFO  (coreLoadExecutor-3969-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1548928415108694016
   [junit4]   2> 860218 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 860218 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 860219 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 860219 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 860219 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 860219 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1bd74216[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 860569 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 860572 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1548928415478841344)} 0 1
   [junit4]   2> 860574 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1548928415480938496)} 0 0
   [junit4]   2> 860576 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[1 (1548928415481987072)]} 0 1
   [junit4]   2> 860580 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[1 (1548928415484084224)]} 0 3
   [junit4]   2> 860581 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[2 (1548928415488278528)]} 0 0
   [junit4]   2> 860581 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[2 (1548928415489327104)]} 0 0
   [junit4]   2> 860583 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[3 (1548928415490375680)]} 0 0
   [junit4]   2> 860583 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[3 (1548928415491424256)]} 0 0
   [junit4]   2> 860584 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[4 (1548928415492472832)]} 0 0
   [junit4]   2> 860585 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[4 (1548928415493521408)]} 0 0
   [junit4]   2> 860586 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[5 (1548928415493521408)]} 0 0
   [junit4]   2> 860587 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[5 (1548928415494569984)]} 0 0
   [junit4]   2> 860587 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[6 (1548928415495618560)]} 0 0
   [junit4]   2> 860588 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[6 (1548928415496667136)]} 0 0
   [junit4]   2> 860589 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[7 (1548928415496667136)]} 0 0
   [junit4]   2> 860589 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[7 (1548928415497715712)]} 0 0
   [junit4]   2> 860590 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[8 (1548928415498764288)]} 0 0
   [junit4]   2> 860591 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[8 (1548928415499812864)]} 0 0
   [junit4]   2> 860592 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[9 (1548928415499812864)]} 0 0
   [junit4]   2> 860593 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[9 (1548928415500861440)]} 0 0
   [junit4]   2> 860593 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 860593 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1022f16
   [junit4]   2> 860599 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7a02be43[collection1] main]
   [junit4]   2> 860599 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 860600 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7a02be43[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 860600 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 860600 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 860601 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@678a1fbf
   [junit4]   2> 860606 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@724d0648[collection1] main]
   [junit4]   2> 860606 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 860606 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@724d0648[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 860606 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 860609 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[10 (1548928415516590080)]} 0 1
   [junit4]   2> 860610 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[10 (1548928415518687232)]} 0 1
   [junit4]   2> 860612 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[11 (1548928415520784384)]} 0 1
   [junit4]   2> 860612 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[11 (1548928415521832960)]} 0 0
   [junit4]   2> 860613 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[12 (1548928415522881536)]} 0 0
   [junit4]   2> 860614 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[12 (1548928415522881536)]} 0 0
   [junit4]   2> 860614 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[13 (1548928415523930112)]} 0 0
   [junit4]   2> 860615 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[13 (1548928415524978688)]} 0 0
   [junit4]   2> 860616 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[1001 (1548928415524978688)]} 0 0
   [junit4]   2> 860617 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[1001 (1548928415526027264)]} 0 0
   [junit4]   2> 860617 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[14 (1548928415527075840)]} 0 0
   [junit4]   2> 860618 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[14 (1548928415528124416)]} 0 0
   [junit4]   2> 860619 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[15 (1548928415528124416)]} 0 0
   [junit4]   2> 860619 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[15 (1548928415529172992)]} 0 0
   [junit4]   2> 860620 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[16 (1548928415530221568)]} 0 0
   [junit4]   2> 860620 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[16 (1548928415530221568)]} 0 0
   [junit4]   2> 860621 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[17 (1548928415531270144)]} 0 0
   [junit4]   2> 860622 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[17 (1548928415532318720)]} 0 0
   [junit4]   2> 860623 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[100 (1548928415532318720)]} 0 0
   [junit4]   2> 860623 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[100 (1548928415533367296)]} 0 0
   [junit4]   2> 860624 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[101 (1548928415533367296)]} 0 0
   [junit4]   2> 860624 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[101 (1548928415534415872)]} 0 0
   [junit4]   2> 860625 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[102 (1548928415535464448)]} 0 0
   [junit4]   2> 860625 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[102 (1548928415535464448)]} 0 0
   [junit4]   2> 860626 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[103 (1548928415536513024)]} 0 0
   [junit4]   2> 860627 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[103 (1548928415536513024)]} 0 0
   [junit4]   2> 860627 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[104 (1548928415537561600)]} 0 0
   [junit4]   2> 860628 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[104 (1548928415537561600)]} 0 0
   [junit4]   2> 860628 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[105 (1548928415538610176)]} 0 0
   [junit4]   2> 860629 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[105 (1548928415538610176)]} 0 0
   [junit4]   2> 860629 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[106 (1548928415539658752)]} 0 0
   [junit4]   2> 860630 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[106 (1548928415540707328)]} 0 0
   [junit4]   2> 860630 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[107 (1548928415540707328)]} 0 0
   [junit4]   2> 860631 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[107 (1548928415541755904)]} 0 0
   [junit4]   2> 860635 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[108 (1548928415545950208)]} 0 0
   [junit4]   2> 860636 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[108 (1548928415546998784)]} 0 0
   [junit4]   2> 860636 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[109 (1548928415546998784)]} 0 0
   [junit4]   2> 860637 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[109 (1548928415548047360)]} 0 0
   [junit4]   2> 860638 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[110 (1548928415548047360)]} 0 0
   [junit4]   2> 860638 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[110 (1548928415549095936)]} 0 0
   [junit4]   2> 860639 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[111 (1548928415549095936)]} 0 0
   [junit4]   2> 860639 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[111 (1548928415550144512)]} 0 0
   [junit4]   2> 860640 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[112 (1548928415551193088)]} 0 0
   [junit4]   2> 860640 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[112 (1548928415551193088)]} 0 0
   [junit4]   2> 860641 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[113 (1548928415552241664)]} 0 0
   [junit4]   2> 860641 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[113 (1548928415552241664)]} 0 0
   [junit4]   2> 860642 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[114 (1548928415553290240)]} 0 0
   [junit4]   2> 860643 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[114 (1548928415554338816)]} 0 0
   [junit4]   2> 860644 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[115 (1548928415554338816)]} 0 0
   [junit4]   2> 860644 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[115 (1548928415555387392)]} 0 0
   [junit4]   2> 860645 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[116 (1548928415555387392)]} 0 0
   [junit4]   2> 860645 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[116 (1548928415556435968)]} 0 0
   [junit4]   2> 860646 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[117 (1548928415557484544)]} 0 0
   [junit4]   2> 860646 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[117 (1548928415557484544)]} 0 0
   [junit4]   2> 860647 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[118 (1548928415558533120)]} 0 0
   [junit4]   2> 860647 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[118 (1548928415558533120)]} 0 0
   [junit4]   2> 860648 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[119 (1548928415559581696)]} 0 0
   [junit4]   2> 860649 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[119 (1548928415559581696)]} 0 0
   [junit4]   2> 860649 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[120 (1548928415560630272)]} 0 0
   [junit4]   2> 860650 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[120 (1548928415560630272)]} 0 0
   [junit4]   2> 860650 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[121 (1548928415561678848)]} 0 0
   [junit4]   2> 860651 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[121 (1548928415561678848)]} 0 0
   [junit4]   2> 860653 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[122 (1548928415564824576)]} 0 0
   [junit4]   2> 860653 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[122 (1548928415564824576)]} 0 0
   [junit4]   2> 860654 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[123 (1548928415565873152)]} 0 0
   [junit4]   2> 860655 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[123 (1548928415566921728)]} 0 0
   [junit4]   2> 860655 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[124 (1548928415566921728)]} 0 0
   [junit4]   2> 860656 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[124 (1548928415567970304)]} 0 0
   [junit4]   2> 860656 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[125 (1548928415567970304)]} 0 0
   [junit4]   2> 860657 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[125 (1548928415569018880)]} 0 0
   [junit4]   2> 860658 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[126 (1548928415569018880)]} 0 0
   [junit4]   2> 860658 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[126 (1548928415570067456)]} 0 0
   [junit4]   2> 860659 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[127 (1548928415570067456)]} 0 0
   [junit4]   2> 860659 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[127 (1548928415571116032)]} 0 0
   [junit4]   2> 860660 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[128 (1548928415571116032)]} 0 0
   [junit4]   2> 860660 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[128 (1548928415572164608)]} 0 0
   [junit4]   2> 860661 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[129 (1548928415573213184)]} 0 0
   [junit4]   2> 860661 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[129 (1548928415573213184)]} 0 0
   [junit4]   2> 860662 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[130 (1548928415574261760)]} 0 0
   [junit4]   2> 860662 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[130 (1548928415574261760)]} 0 0
   [junit4]   2> 860663 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[131 (1548928415575310336)]} 0 0
   [junit4]   2> 860664 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[131 (1548928415575310336)]} 0 0
   [junit4]   2> 860664 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[132 (1548928415576358912)]} 0 0
   [junit4]   2> 860665 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[132 (1548928415576358912)]} 0 0
   [junit4]   2> 860665 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[133 (1548928415577407488)]} 0 0
   [junit4]   2> 860666 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[133 (1548928415578456064)]} 0 0
   [junit4]   2> 860666 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[134 (1548928415578456064)]} 0 0
   [junit4]   2> 860667 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[134 (1548928415579504640)]} 0 0
   [junit4]   2> 860667 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[135 (1548928415579504640)]} 0 0
   [junit4]   2> 860668 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[135 (1548928415580553216)]} 0 0
   [junit4]   2> 860669 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[136 (1548928415580553216)]} 0 0
   [junit4]   2> 860669 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[136 (1548928415581601792)]} 0 0
   [junit4]   2> 860670 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[137 (1548928415581601792)]} 0 0
   [junit4]   2> 860670 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[137 (1548928415582650368)]} 0 0
   [junit4]   2> 860671 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[138 (1548928415582650368)]} 0 0
   [junit4]   2> 860671 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[138 (1548928415583698944)]} 0 0
   [junit4]   2> 860672 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[139 (1548928415583698944)]} 0 0
   [junit4]   2> 860672 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[139 (1548928415584747520)]} 0 0
   [junit4]   2> 860673 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[140 (1548928415584747520)]} 0 0
   [junit4]   2> 860673 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[140 (1548928415585796096)]} 0 0
   [junit4]   2> 860674 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[141 (1548928415586844672)]} 0 0
   [junit4]   2> 860674 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[141 (1548928415586844672)]} 0 0
   [junit4]   2> 860675 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[142 (1548928415587893248)]} 0 0
   [junit4]   2> 860675 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[142 (1548928415587893248)]} 0 0
   [junit4]   2> 860676 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[143 (1548928415588941824)]} 0 0
   [junit4]   2> 860677 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[143 (1548928415588941824)]} 0 0
   [junit4]   2> 860677 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[144 (1548928415589990400)]} 0 0
   [junit4]   2> 860678 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[144 (1548928415589990400)]} 0 0
   [junit4]   2> 860678 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[145 (1548928415591038976)]} 0 0
   [junit4]   2> 860679 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[145 (1548928415591038976)]} 0 0
   [junit4]   2> 860679 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[146 (1548928415592087552)]} 0 0
   [junit4]   2> 860680 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[146 (1548928415592087552)]} 0 0
   [junit4]   2> 860680 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[147 (1548928415593136128)]} 0 0
   [junit4]   2> 860681 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[147 (1548928415593136128)]} 0 0
   [junit4]   2> 860681 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[148 (1548928415594184704)]} 0 0
   [junit4]   2> 860682 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[148 (1548928415595233280)]} 0 0
   [junit4]   2> 860683 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[149 (1548928415596281856)]} 0 0
   [junit4]   2> 860684 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={wt=javabin&version=2}{add=[149 (1548928415597330432)]} 0 0
   [junit4]   2> 860684 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 860684 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1022f16
   [junit4]   2> 860692 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@17141a59[collection1] main]
   [junit4]   2> 860692 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 860693 INFO  
(searcherExecutor-3959-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17141a59[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 860693 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 860693 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 860693 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@678a1fbf
   [junit4]   2> 860701 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@ae2752e[collection1] main]
   [junit4]   2> 860701 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 860702 INFO  
(searcherExecutor-3970-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ae2752e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 860702 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/s_f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 860703 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 860703 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 860704 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 860704 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 860705 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860782 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173247929&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860785 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173247929&ids=144,12,15,125,147,137,138,17,149,129&isShard=true&shard.url=[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860786 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=80
   [junit4]   2> 860787 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860790 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248011&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860791 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248011&ids=132,1,102,103,105,1001,106,118,9,119&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860797 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 860799 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860800 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248022&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860802 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248022&ids=144,2,3,146,137,116,129,9,109,131&isShard=true&shard.url=[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860804 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 860805 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860806 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248029&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860812 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248029&ids=143,11,1,14,113,147,125,103,105,149&isShard=true&shard.url=[ff01::213]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860814 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::213]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 860815 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860816 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248038&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860817 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248038&ids=132,100,13,15,4,126,137,149,106,130&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860819 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 860820 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860848 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f|[ff01::083]:33332/s_f|[ff01::213]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173248044&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860849 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248044&ids=121,134,112,14,124,115,104,128,9,140&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f|[ff01::083]:33332/s_f|[ff01::213]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860851 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f|[ff01::083]:33332/s_f|[ff01::213]:33332/s_f&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=30
   [junit4]   2> 860852 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860854 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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:50753/s_f/collection1|[ff01::213]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173248076&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860854 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248076&ids=100,122,12,13,124,113,3,146,149,130&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860855 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860856 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860857 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::114]:33332/s_f|[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248080&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860858 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248080&ids=132,111,2,101,123,136,148,126,8,109&isShard=true&shard.url=[ff01::114]:33332/s_f|[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860859 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::114]:33332/s_f|[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 860860 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860861 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::083]:33332/s_f|[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248083&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860862 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248083&ids=1,123,14,115,6,106,7,128,107,140&isShard=true&shard.url=[ff01::083]:33332/s_f|[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860862 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::083]:33332/s_f|[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 860863 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860864 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/s_f|[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173248087&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860865 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248087&ids=102,135,125,16,116,1001,139,129,130,141&isShard=true&shard.url=[ff01::114]:33332/s_f|[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860866 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::114]:33332/s_f|[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 860867 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860868 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f|[ff01::213]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173248090&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860869 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248090&ids=143,110,101,134,113,116,149,1001,119,130&isShard=true&shard.url=[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f|[ff01::213]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860869 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::083]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::114]:33332/s_f|[ff01::213]:33332/s_f&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860870 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860871 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248094&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860872 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248094&ids=132,144,100,2,3,148,127,105,118,131&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860873 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860873 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860875 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248097&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860875 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248097&ids=143,145,135,115,104,149,1001,107,108,119&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860876 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860877 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860878 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::083]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173248100&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860879 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248100&ids=11,100,101,124,4,148,16,127,6,9&isShard=true&shard.url=[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::083]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860879 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1|[ff01::083]:33332/s_f&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860880 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860881 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248104&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860882 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248104&ids=132,144,100,2,146,15,147,105,117,109&isShard=true&shard.url=[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860882 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860883 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860884 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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:50753/s_f/collection1|[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|[ff01::114]:33332/s_f&rows=10&version=2&q=*:*&NOW=1477173248107&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860885 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248107&ids=134,135,14,125,4,104,137,106,118,129&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|[ff01::114]:33332/s_f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860886 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1|[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|[ff01::114]:33332/s_f&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860886 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860888 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248110&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860888 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248110&ids=111,134,147,148,116,7,117,8,141,131&isShard=true&shard.url=[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860889 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860890 INFO  (qtp1405619351-11316) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860891 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248113&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860892 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248113&ids=143,101,15,4,125,5,115,127,130,142&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860892 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860893 INFO  (qtp1405619351-11320) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860894 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248117&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860895 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248117&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860896 INFO  (qtp499838676-11335) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 860897 INFO  (qtp1405619351-11313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 860899 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248121&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860899 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248121&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860900 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 860901 INFO  (qtp1405619351-11319) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 860902 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248124&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860903 INFO  (qtp499838676-11337) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477173248124&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860903 INFO  (qtp499838676-11334) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=http://127.0.0.1:50753/s_f/collection1&sort=a_i1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860904 INFO  (qtp1405619351-11317) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 860905 INFO  (qtp499838676-11331) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248128&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860906 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1477173248128&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860907 INFO  (qtp499838676-11330) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|[ff01::114]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&fl=*,score&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860908 INFO  (qtp1405619351-11318) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 860909 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f 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/s_f|http://127.0.0.1:50753/s_f/collection1&rows=10&version=2&q=*:*&NOW=1477173248131&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 860910 INFO  (qtp499838676-11336) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1477173248131&ids=11,100,101,124,4,148,16,127,6,9&isShard=true&shard.url=[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 860910 INFO  (qtp499838676-11333) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={q=*:*&shards=[ff01::213]:33332/s_f|http://127.0.0.1:50753/s_f/collection1&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 860911 INFO  (qtp14

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

true&NOW=1477173268889&isShard=true&timeAllowed=1&wt=javabin&group=true} 
status=0 QTime=2
   [junit4]   2> 881679 INFO  (qtp365167703-11374) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/s_f path=/select 
params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::213]:33332/s_f|[ff01::083]:33332/s_f|http://127.0.0.1:35350/s_f/collection1|[ff01::114]:33332/s_f,[ff01::213]:33332/s_f|http://127.0.0.1:46415/s_f/collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=12
   [junit4]   2> 881681 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 881682 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@2f6f2ef0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 881682 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1214161848
   [junit4]   2> 881682 INFO  (coreCloseExecutor-4012-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@264883d0
   [junit4]   2> 881689 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4f8c16a4{/s_f,null,UNAVAILABLE}
   [junit4]   2> 881690 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@58695f6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 881690 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2036361218
   [junit4]   2> 881691 INFO  (coreCloseExecutor-4014-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@17cf1deb
   [junit4]   2> 881697 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@59839f00{/s_f,null,UNAVAILABLE}
   [junit4]   2> 881698 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@18ecfcae{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 881698 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1412322798
   [junit4]   2> 881698 INFO  (coreCloseExecutor-4016-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@33aa0b65
   [junit4]   2> 881706 INFO  
(TEST-TestDistributedSearch.test-seed#[AE8E775EE7EF5666]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7f4f3b1d{/s_f,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=AE8E775EE7EF5666 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=sl-SI -Dtests.timezone=GMT0 -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 22.3s 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:226)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:198)
   [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 
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=2}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([AE8E775EE7EF5666:26DA488449133B9E]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1172)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1113)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:973)
   [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> 881709 INFO  
(SUITE-TestDistributedSearch-seed#[AE8E775EE7EF5666]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AE8E775EE7EF5666-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{other_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
range_facet_l_dv=Lucene50(blocksize=128), 
SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
n_l1=PostingsFormat(name=LuceneVarGapFixedInterval), a_n_tdt=FSTOrd50, 
multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), 
intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
n_td1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
n_d1=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_f1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
oddField_s=Lucene50(blocksize=128), 
n_tf1=PostingsFormat(name=LuceneVarGapFixedInterval), 
lowerfilt1and2=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_t=PostingsFormat(name=LuceneVarGapFixedInterval), 
lowerfilt=PostingsFormat(name=LuceneVarGapFixedInterval), 
id=Lucene50(blocksize=128), text=FSTOrd50, 
timestamp=PostingsFormat(name=LuceneVarGapFixedInterval), severity=FSTOrd50, 
foo_sev_enum=FSTOrd50, n_dt1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), 
b_n_tdt=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
n_ti1=Lucene50(blocksize=128), rnd_b=Lucene50(blocksize=128), 
_version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), foo_b=FSTOrd50, 
n_tdt1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
foo_d=Lucene50(blocksize=128), 
range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), 
foo_f=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), 
foo_sev_enum=DocValuesFormat(name=Lucene70), 
range_facet_i_dv=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=945, 
maxMBSortInHeap=7.900727547580933, sim=ClassicSimilarity, locale=sl-SI, 
timezone=GMT0
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.8.0_102 (64-bit)/cpus=4,threads=1,free=119213192,total=532152320
   [junit4]   2> NOTE: All tests run in this JVM: 
[ParsingFieldUpdateProcessorsTest, TestFieldCollectionResource, 
TestSolr4Spatial, TestLeaderInitiatedRecoveryThread, TestSQLHandler, 
TestCrossCoreJoin, TestRecoveryHdfs, RequiredFieldsTest, TestRemoteStreaming, 
TestCloudSchemaless, BasicZkTest, TestNRTOpen, LeaderElectionIntegrationTest, 
ScriptEngineTest, MinimalSchemaTest, TestLFUCache, TestCustomSort, 
AnalyticsQueryTest, TestDistribDocBasedVersion, TestReplicationHandlerBackup, 
TestLegacyFieldCache, TestManagedResource, TestOrdValues, 
UniqFieldsUpdateProcessorFactoryTest, TestHdfsCloudBackupRestore, 
ZkNodePropsTest, TestLazyCores, HdfsDirectoryFactoryTest, 
TestConfigSetsAPIExclusivity, DeleteShardTest, ExplicitHLLTest, 
TestManagedSchemaAPI, SignatureUpdateProcessorFactoryTest, TestLRUCache, 
BooleanFieldTest, DocumentAnalysisRequestHandlerTest, OverseerTaskQueueTest, 
SolrRequestParserTest, TestAtomicUpdateErrorCases, 
TlogReplayBufferedWhileIndexingTest, TestStressLucene, TestRealTimeGet, 
TestJmxIntegration, TestRandomFaceting, QueryEqualityTest, 
TestManagedStopFilterFactory, TestCloudPseudoReturnFields, 
HdfsChaosMonkeySafeLeaderTest, TestGraphMLResponseWriter, TestRandomDVFaceting, 
StandardRequestHandlerTest, TestReplicationHandler, 
TestSuggestSpellingConverter, TestSolrQueryParserDefaultOperatorResource, 
CoreSorterTest, ClusterStateTest, SegmentsInfoRequestHandlerTest, 
TestFieldTypeResource, SolrCoreTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
JavabinLoaderTest, TestClassicSimilarityFactory, TestSolrConfigHandler, 
SyncSliceTest, DefaultValueUpdateProcessorTest, RemoteQueryErrorTest, 
TestSolrQueryResponse, TestBlobHandler, CdcrVersionReplicationTest, 
PeerSyncTest, HdfsBasicDistributedZk2Test, RestartWhileUpdatingTest, 
TestPerFieldSimilarityWithDefaultOverride, PluginInfoTest, 
SuggestComponentContextFilterQueryTest, NotRequiredUniqueKeyTest, 
TestValueSourceCache, TestMiniSolrCloudClusterSSL, ReturnFieldsTest, 
CollectionTooManyReplicasTest, TestLRUStatsCache, 
LeaderFailoverAfterPartitionTest, BigEndianAscendingWordDeserializerTest, 
TestSolrDeletionPolicy2, TestDistribIDF, TestMacros, TestExceedMaxTermLength, 
TestDistributedSearch]
   [junit4] Completed [302/641 (1!)] on J0 in 22.32s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 61436 lines...]


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

Reply via email to