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

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18118/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseG1GC

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=1}

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: No live SolrServers 
available to handle this 
request,trace=org.apache.solr.client.solrj.SolrServerException: No live 
SolrServers available to handle this request
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java: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=1}
        at 
__randomizedtesting.SeedInfo.seed([FAF948EB786C9545:72AD7731D690F8BD]: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 11718 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/init-core-data-001
   [junit4]   2> 1076963 INFO  
(SUITE-TestDistributedSearch-seed#[FAF948EB786C9545]-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> 1076964 INFO  
(SUITE-TestDistributedSearch-seed#[FAF948EB786C9545]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1077232 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1077233 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 1077234 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@424cff81{/,null,AVAILABLE}
   [junit4]   2> 1077238 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@78f3c740{HTTP/1.1,[http/1.1]}{127.0.0.1:46316}
   [junit4]   2> 1077238 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] o.e.j.s.Server 
Started @1079194ms
   [junit4]   2> 1077238 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=46316, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/control/cores}
   [junit4]   2> 1077238 ERROR 
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1077239 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1077239 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1077239 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1077239 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-23T03:12:26.436Z
   [junit4]   2> 1077239 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/control/solr.xml
   [junit4]   2> 1077262 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/control/cores
   [junit4]   2> 1077262 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1077262 INFO  (coreLoadExecutor-3685-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1077281 WARN  (coreLoadExecutor-3685-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1077282 INFO  (coreLoadExecutor-3685-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1077310 INFO  (coreLoadExecutor-3685-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1077362 WARN  (coreLoadExecutor-3685-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> 1077363 INFO  (coreLoadExecutor-3685-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1077368 INFO  (coreLoadExecutor-3685-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1077368 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1077369 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@61790af7
   [junit4]   2> 1077369 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1077377 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1077377 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1077377 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1077377 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1077378 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=34.076171875, 
floorSegmentMB=0.9306640625, forceMergeDeletesPctAllowed=15.74135845165905, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1077378 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@650ad548[collection1] main]
   [junit4]   2> 1077378 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1078942 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1079274 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1079274 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1079274 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1079274 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1079274 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1079275 INFO  (coreLoadExecutor-3685-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1548948443991375872
   [junit4]   2> 1079275 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1079275 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1079275 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1079275 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1079275 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1079276 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@650ad548[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1079855 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1079856 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] o.e.j.s.Server 
jetty-9.3.8.v20160314
   [junit4]   2> 1079857 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1d77e3b1{/,null,AVAILABLE}
   [junit4]   2> 1079857 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@51e2ad8b{HTTP/1.1,[http/1.1]}{127.0.0.1:44737}
   [junit4]   2> 1079858 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] o.e.j.s.Server 
Started @1081814ms
   [junit4]   2> 1079858 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=44737, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/shard0/cores}
   [junit4]   2> 1079858 ERROR 
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1079858 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 1079858 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1079858 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1079858 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-23T03:12:29.055Z
   [junit4]   2> 1079859 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1079877 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/shard0/cores
   [junit4]   2> 1079878 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1079878 INFO  (coreLoadExecutor-3696-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1079898 WARN  (coreLoadExecutor-3696-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1079899 INFO  (coreLoadExecutor-3696-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1079916 INFO  (coreLoadExecutor-3696-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1079983 WARN  (coreLoadExecutor-3696-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> 1079986 INFO  (coreLoadExecutor-3696-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1079990 INFO  (coreLoadExecutor-3696-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 1079990 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1079990 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@61790af7
   [junit4]   2> 1079991 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1080001 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1080001 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1080001 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1080001 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1080002 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=34.076171875, 
floorSegmentMB=0.9306640625, forceMergeDeletesPctAllowed=15.74135845165905, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1080002 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7d008923[collection1] main]
   [junit4]   2> 1080002 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1080032 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1080262 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1080262 INFO  (coreLoadExecutor-3696-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1548948445026320384
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1080262 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1080263 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1080263 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d008923[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1080380 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1080384 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1548948445152149504)} 0 2
   [junit4]   2> 1080388 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1548948445156343808)} 0 2
   [junit4]   2> 1080390 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1548948445159489536)]} 0 1
   [junit4]   2> 1080392 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1548948445161586688)]} 0 1
   [junit4]   2> 1080394 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1548948445164732416)]} 0 0
   [junit4]   2> 1080395 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1548948445165780992)]} 0 0
   [junit4]   2> 1080396 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1548948445165780992)]} 0 0
   [junit4]   2> 1080397 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1548948445166829568)]} 0 0
   [junit4]   2> 1080398 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1548948445167878144)]} 0 0
   [junit4]   2> 1080399 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1548948445168926720)]} 0 0
   [junit4]   2> 1080400 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1548948445169975296)]} 0 0
   [junit4]   2> 1080400 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1548948445171023872)]} 0 0
   [junit4]   2> 1080401 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1548948445172072448)]} 0 0
   [junit4]   2> 1080402 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1548948445173121024)]} 0 0
   [junit4]   2> 1080403 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1548948445173121024)]} 0 0
   [junit4]   2> 1080407 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1548948445177315328)]} 0 0
   [junit4]   2> 1080408 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1548948445178363904)]} 0 0
   [junit4]   2> 1080409 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1548948445179412480)]} 0 0
   [junit4]   2> 1080410 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1548948445180461056)]} 0 0
   [junit4]   2> 1080410 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1548948445181509632)]} 0 0
   [junit4]   2> 1080411 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1080411 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@206ac053
   [junit4]   2> 1080418 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@428dcf76[collection1] main]
   [junit4]   2> 1080418 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1080419 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@428dcf76[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 1080419 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 1080420 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1080420 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b74e6a4
   [junit4]   2> 1080427 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@32882013[collection1] main]
   [junit4]   2> 1080427 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1080428 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@32882013[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)))}
   [junit4]   2> 1080429 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 9
   [junit4]   2> 1080433 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1548948445202481152)]} 0 3
   [junit4]   2> 1080435 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1548948445206675456)]} 0 1
   [junit4]   2> 1080436 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1548948445208772608)]} 0 0
   [junit4]   2> 1080438 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1548948445209821184)]} 0 1
   [junit4]   2> 1080439 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1548948445211918336)]} 0 0
   [junit4]   2> 1080440 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1548948445212966912)]} 0 0
   [junit4]   2> 1080442 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1548948445214015488)]} 0 0
   [junit4]   2> 1080443 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1548948445215064064)]} 0 0
   [junit4]   2> 1080444 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1548948445216112640)]} 0 0
   [junit4]   2> 1080445 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1548948445218209792)]} 0 0
   [junit4]   2> 1080446 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1548948445219258368)]} 0 0
   [junit4]   2> 1080446 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1548948445219258368)]} 0 0
   [junit4]   2> 1080447 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1548948445220306944)]} 0 0
   [junit4]   2> 1080448 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1548948445220306944)]} 0 0
   [junit4]   2> 1080448 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1548948445221355520)]} 0 0
   [junit4]   2> 1080449 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1548948445222404096)]} 0 0
   [junit4]   2> 1080450 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1548948445223452672)]} 0 0
   [junit4]   2> 1080451 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1548948445223452672)]} 0 0
   [junit4]   2> 1080451 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1548948445224501248)]} 0 0
   [junit4]   2> 1080453 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1548948445225549824)]} 0 0
   [junit4]   2> 1080454 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1548948445227646976)]} 0 0
   [junit4]   2> 1080454 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1548948445227646976)]} 0 0
   [junit4]   2> 1080455 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1548948445228695552)]} 0 0
   [junit4]   2> 1080456 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1548948445228695552)]} 0 0
   [junit4]   2> 1080456 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1548948445229744128)]} 0 0
   [junit4]   2> 1080457 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1548948445230792704)]} 0 0
   [junit4]   2> 1080457 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1548948445230792704)]} 0 0
   [junit4]   2> 1080458 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1548948445231841280)]} 0 0
   [junit4]   2> 1080459 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1548948445231841280)]} 0 0
   [junit4]   2> 1080459 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1548948445232889856)]} 0 0
   [junit4]   2> 1080460 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1548948445233938432)]} 0 0
   [junit4]   2> 1080460 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1548948445233938432)]} 0 0
   [junit4]   2> 1080461 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1548948445234987008)]} 0 0
   [junit4]   2> 1080462 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1548948445234987008)]} 0 0
   [junit4]   2> 1080463 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1548948445236035584)]} 0 0
   [junit4]   2> 1080463 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1548948445237084160)]} 0 0
   [junit4]   2> 1080464 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1548948445238132736)]} 0 0
   [junit4]   2> 1080465 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1548948445239181312)]} 0 1
   [junit4]   2> 1080466 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1548948445240229888)]} 0 0
   [junit4]   2> 1080467 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1548948445240229888)]} 0 0
   [junit4]   2> 1080467 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1548948445241278464)]} 0 0
   [junit4]   2> 1080468 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1548948445241278464)]} 0 0
   [junit4]   2> 1080468 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1548948445242327040)]} 0 0
   [junit4]   2> 1080469 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1548948445243375616)]} 0 0
   [junit4]   2> 1080469 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1548948445243375616)]} 0 0
   [junit4]   2> 1080470 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1548948445244424192)]} 0 0
   [junit4]   2> 1080470 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1548948445244424192)]} 0 0
   [junit4]   2> 1080471 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1548948445245472768)]} 0 0
   [junit4]   2> 1080472 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1548948445245472768)]} 0 0
   [junit4]   2> 1080472 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1548948445246521344)]} 0 0
   [junit4]   2> 1080473 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1548948445246521344)]} 0 0
   [junit4]   2> 1080473 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1548948445247569920)]} 0 0
   [junit4]   2> 1080474 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1548948445248618496)]} 0 0
   [junit4]   2> 1080474 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1548948445248618496)]} 0 0
   [junit4]   2> 1080475 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1548948445249667072)]} 0 0
   [junit4]   2> 1080475 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1548948445249667072)]} 0 0
   [junit4]   2> 1080477 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1548948445250715648)]} 0 1
   [junit4]   2> 1080478 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1548948445251764224)]} 0 0
   [junit4]   2> 1080478 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1548948445252812800)]} 0 0
   [junit4]   2> 1080479 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1548948445253861376)]} 0 0
   [junit4]   2> 1080480 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1548948445253861376)]} 0 0
   [junit4]   2> 1080480 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1548948445254909952)]} 0 0
   [junit4]   2> 1080481 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1548948445254909952)]} 0 0
   [junit4]   2> 1080481 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1548948445255958528)]} 0 0
   [junit4]   2> 1080482 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1548948445255958528)]} 0 0
   [junit4]   2> 1080482 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1548948445257007104)]} 0 0
   [junit4]   2> 1080483 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1548948445258055680)]} 0 0
   [junit4]   2> 1080483 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1548948445258055680)]} 0 0
   [junit4]   2> 1080484 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1548948445259104256)]} 0 0
   [junit4]   2> 1080484 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1548948445259104256)]} 0 0
   [junit4]   2> 1080485 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1548948445260152832)]} 0 0
   [junit4]   2> 1080486 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1548948445260152832)]} 0 0
   [junit4]   2> 1080488 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1548948445262249984)]} 0 0
   [junit4]   2> 1080489 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1548948445263298560)]} 0 1
   [junit4]   2> 1080490 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1548948445264347136)]} 0 0
   [junit4]   2> 1080490 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1548948445265395712)]} 0 0
   [junit4]   2> 1080491 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1548948445266444288)]} 0 0
   [junit4]   2> 1080491 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1548948445266444288)]} 0 0
   [junit4]   2> 1080493 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1548948445268541440)]} 0 0
   [junit4]   2> 1080494 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1548948445268541440)]} 0 0
   [junit4]   2> 1080494 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1548948445269590016)]} 0 0
   [junit4]   2> 1080495 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1548948445270638592)]} 0 0
   [junit4]   2> 1080495 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1548948445270638592)]} 0 0
   [junit4]   2> 1080496 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1548948445271687168)]} 0 0
   [junit4]   2> 1080497 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1548948445272735744)]} 0 0
   [junit4]   2> 1080498 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1548948445272735744)]} 0 0
   [junit4]   2> 1080498 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1548948445273784320)]} 0 0
   [junit4]   2> 1080499 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1548948445274832896)]} 0 0
   [junit4]   2> 1080500 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1548948445274832896)]} 0 0
   [junit4]   2> 1080502 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1548948445276930048)]} 0 0
   [junit4]   2> 1080502 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1548948445277978624)]} 0 0
   [junit4]   2> 1080503 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1548948445279027200)]} 0 0
   [junit4]   2> 1080504 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1548948445279027200)]} 0 0
   [junit4]   2> 1080505 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1548948445280075776)]} 0 0
   [junit4]   2> 1080505 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1548948445281124352)]} 0 0
   [junit4]   2> 1080506 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1548948445282172928)]} 0 0
   [junit4]   2> 1080507 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1548948445282172928)]} 0 0
   [junit4]   2> 1080507 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1548948445283221504)]} 0 0
   [junit4]   2> 1080508 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1548948445284270080)]} 0 0
   [junit4]   2> 1080509 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1548948445284270080)]} 0 0
   [junit4]   2> 1080509 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1548948445285318656)]} 0 0
   [junit4]   2> 1080510 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1548948445285318656)]} 0 0
   [junit4]   2> 1080510 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1548948445286367232)]} 0 0
   [junit4]   2> 1080511 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1548948445286367232)]} 0 0
   [junit4]   2> 1080511 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1548948445287415808)]} 0 0
   [junit4]   2> 1080512 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1548948445287415808)]} 0 0
   [junit4]   2> 1080512 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1548948445288464384)]} 0 0
   [junit4]   2> 1080514 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1548948445289512960)]} 0 0
   [junit4]   2> 1080514 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1548948445290561536)]} 0 0
   [junit4]   2> 1080515 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1548948445291610112)]} 0 0
   [junit4]   2> 1080516 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1548948445291610112)]} 0 0
   [junit4]   2> 1080516 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1548948445292658688)]} 0 0
   [junit4]   2> 1080517 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1548948445293707264)]} 0 0
   [junit4]   2> 1080517 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1548948445293707264)]} 0 0
   [junit4]   2> 1080518 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1548948445294755840)]} 0 0
   [junit4]   2> 1080518 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1548948445294755840)]} 0 0
   [junit4]   2> 1080519 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1548948445295804416)]} 0 0
   [junit4]   2> 1080520 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1548948445296852992)]} 0 0
   [junit4]   2> 1080520 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1080520 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@206ac053
   [junit4]   2> 1080529 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1348a479[collection1] main]
   [junit4]   2> 1080530 INFO  
(searcherExecutor-3686-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1348a479[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 1080530 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1080530 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 10
   [junit4]   2> 1080531 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1080531 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@b74e6a4
   [junit4]   2> 1080543 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@387e6c06[collection1] main]
   [junit4]   2> 1080543 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1080544 INFO  
(searcherExecutor-3697-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@387e6c06[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C9)
 Uninverting(_1(7.0.0):C59)))}
   [junit4]   2> 1080544 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 13
   [junit4]   2> 1080546 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1080546 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1080547 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1080547 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1080549 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 1080556 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1477192349749&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080560 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349749&ids=143,144,100,122,2,17,128,8,118,131&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080563 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=11
   [junit4]   2> 1080565 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080569 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349764&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080570 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349764&ids=121,12,13,102,113,138,107,109,140,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080580 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:44737//collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=13
   [junit4]   2> 1080581 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080584 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349780&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080586 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349780&ids=121,100,4,125,103,104,117,107,130,120&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080594 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=11
   [junit4]   2> 1080596 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080602 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:44737//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1477192349798&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080604 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349798&ids=110,143,1,133,123,124,136,5,116,8&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080605 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::213]:33332/&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1080607 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080609 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349805&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080611 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349805&ids=1,101,15,125,149,138,139,128,8,141&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080614 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 1080616 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080618 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349814&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080620 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349814&ids=111,144,103,126,6,1001,107,9,109,130&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080627 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 1080628 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080633 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1477192349826&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 1080634 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349826&ids=143,145,112,125,147,138,128,118,140,10&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080635 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 1080637 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080639 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1477192349835&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080641 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349835&ids=1,2,14,3,137,105,127,117,107,9&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080642 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080644 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080648 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1477192349842&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080650 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349842&ids=143,134,123,15,103,16,1001,119,130,142&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080651 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1080652 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080654 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:44737//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1477192349850&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080655 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349850&ids=132,11,100,137,138,107,8,118,109,131&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:44737//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080656 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:44737//collection1|[ff01::083]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1080658 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080659 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:44737//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1477192349856&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080661 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349856&ids=110,143,100,123,113,17,149,117,119,10&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080661 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::213]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1080662 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080665 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1477192349861&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080666 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349861&ids=121,2,103,136,16,1001,108,140,141,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080667 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1080668 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080670 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1477192349866&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080671 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349866&ids=11,134,112,124,135,15,114,117,106,131&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080672 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080674 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080675 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349871&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080676 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349871&ids=100,145,3,102,147,104,149,105,128,1001&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080677 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1080678 INFO  (qtp1571212429-7887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080681 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349877&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080682 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349877&ids=110,12,2,113,148,116,8,108,141,131&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080683 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080684 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080690 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349886&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080691 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349886&ids=100,101,3,4,114,17,127,138,128,118&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080692 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44737//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080694 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080697 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349893&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080699 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349893&ids=110,134,2,101,135,4,114,148,115,119&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080700 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:44737//collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080701 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080703 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349899&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080705 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349899&ids=100,112,124,146,3,147,17,108,109,142&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080706 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1080708 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080710 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349906&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080711 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349906&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080713 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080716 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1080718 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349915&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080719 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349915&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080720 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44737//collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1080722 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080728 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1477192349924&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080730 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349924&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080731 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44737//collection1|[ff01::114]:33332/&sort=a_i1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1080732 INFO  (qtp1571212429-7882) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 1080735 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:44737//collection1&rows=10&version=2&q=*:*&NOW=1477192349931&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080736 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1477192349931&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44737//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080737 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1&fl=*,score&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080739 INFO  (qtp1571212429-7889) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 1080741 INFO  (qtp568134569-7900) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:44737//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1477192349937&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080742 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1477192349937&ids=100,145,3,102,147,104,149,105,128,1001&isShard=true&shard.url=http://127.0.0.1:44737//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080743 INFO  (qtp568134569-7899) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44737//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1080744 INFO  (qtp1571212429-7888) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1080746 INFO  (qtp568134569-7906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1477192349942&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1080747 INFO  (qtp568134569-7904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1477192349942&ids=11,134,112,124,135,15,114,117,106,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1080748 INFO  (qtp568134569-7905) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:44737//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
  

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

ebapp= path=/select 
params={distrib=false&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.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 hits=69 status=0 QTime=0
   [junit4]   2> 1120753 INFO  (qtp981264613-8026) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i1+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:44173//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1477192389948&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=0
   [junit4]   2> 1120765 INFO  (qtp981264613-8026) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id,a_i1,id&group.limit=10&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:44173//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&NOW=1477192389948&ids=8&isShard=true&timeAllowed=1&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 1120765 INFO  (qtp916714812-8008) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:36919//collection1|[ff01::114]:33332/|[ff01::213]:33332/,http://127.0.0.1:44173//collection1,[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:42402//collection1|[ff01::213]:33332/&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=13
   [junit4]   2> 1120766 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 1120767 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@40bbcb11{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1120767 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=788441406
   [junit4]   2> 1120767 INFO  (coreCloseExecutor-3791-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2f28826b
   [junit4]   2> 1120771 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1dbdaab0{/,null,UNAVAILABLE}
   [junit4]   2> 1120773 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@644164ed{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1120773 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=970876158
   [junit4]   2> 1120775 INFO  (coreCloseExecutor-3793-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@145e5fb9
   [junit4]   2> 1120779 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@354deec{/,null,UNAVAILABLE}
   [junit4]   2> 1120780 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@77200c36{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1120780 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=209034924
   [junit4]   2> 1120781 INFO  (coreCloseExecutor-3795-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6f2a9abf
   [junit4]   2> 1120786 INFO  
(TEST-TestDistributedSearch.test-seed#[FAF948EB786C9545]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@909089b{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=FAF948EB786C9545 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=hr-HR -Dtests.timezone=America/Denver -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 43.8s J1 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: No live SolrServers 
available to handle this 
request,trace=org.apache.solr.client.solrj.SolrServerException: No live 
SolrServers available to handle this request
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:416)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java: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=1}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([FAF948EB786C9545:72AD7731D690F8BD]: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> 1120790 INFO  
(SUITE-TestDistributedSearch-seed#[FAF948EB786C9545]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_FAF948EB786C9545-001
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=1, maxDocsPerChunk=8, blockSize=10), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=1, blockSize=10)), sim=RandomSimilarity(queryNorm=true): {}, 
locale=hr-HR, timezone=America/Denver
   [junit4]   2> NOTE: Linux 4.4.0-42-generic amd64/Oracle Corporation 
1.8.0_102 (64-bit)/cpus=12,threads=2,free=255655256,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [DocValuesTest, 
TestUseDocValuesAsStored2, TestSearchPerf, ResponseHeaderTest, 
PreAnalyzedUpdateProcessorTest, TestDynamicFieldCollectionResource, 
HighlighterTest, TestDocTermOrds, CoreAdminCreateDiscoverTest, 
HighlighterMaxOffsetTest, TestTestInjection, ConfigSetsAPITest, 
TestManagedResourceStorage, TestBlendedInfixSuggestions, TestNumericTerms32, 
SolrXmlInZkTest, TestHighFrequencyDictionaryFactory, TestCoreContainer, 
BlockJoinFacetRandomTest, TestInfoStreamLogging, 
TriLevelCompositeIdRoutingTest, DeleteStatusTest, TestReloadDeadlock, 
RollingRestartTest, TestFieldSortValues, TestConfigSetsAPIZkFailure, 
TestMaxScoreQueryParser, TestHashQParserPlugin, TestJettySolrRunner, 
HdfsRecoveryZkTest, TestSchemaVersionResource, 
OpenExchangeRatesOrgProviderTest, DirectoryFactoryTest, ShardRoutingCustomTest, 
JsonLoaderTest, TestManagedSchemaThreadSafety, QueryParsingTest, 
TestElisionMultitermQuery, InfoHandlerTest, TestStressVersions, 
TestRequestStatusCollectionAPI, TestQueryUtils, TestPostingsSolrHighlighter, 
TestConfigSetImmutable, ChaosMonkeySafeLeaderTest, TestObjectReleaseTracker, 
SpellingQueryConverterTest, TestManagedSynonymFilterFactory, 
TestLeaderElectionWithEmptyReplica, TestSubQueryTransformerCrossCore, 
SchemaVersionSpecificBehaviorTest, ZkSolrClientTest, TestSolrCoreSnapshots, 
TestCustomStream, PeerSyncReplicationTest, CloudMLTQParserTest, 
LeaderFailureAfterFreshStartTest, TestXmlQParser, SimplePostToolTest, 
DistributedQueryComponentCustomSortTest, TestSolrCloudWithSecureImpersonation, 
IndexBasedSpellCheckerTest, DOMUtilTest, IndexSchemaTest, 
SolrCmdDistributorTest, TestSolrQueryParserResource, 
TestTolerantUpdateProcessorCloud, HdfsBasicDistributedZkTest, 
TestSweetSpotSimilarityFactory, DistanceFunctionTest, StatsReloadRaceTest, 
ShardSplitTest, TestCollationFieldDocValues, OverseerRolesTest, 
TestPartialUpdateDeduplication, ClusterStateUpdateTest, ChangedSchemaMergeTest, 
SparseHLLTest, DistributedTermsComponentTest, TestSQLHandlerNonCloud, 
SpellCheckCollatorTest, DeleteNodeTest, BufferStoreTest, ReplaceNodeTest, 
TestSolrIndexConfig, TestScoreJoinQPNoScore, TestHdfsUpdateLog, 
TestSizeLimitedDistributedMap, TestOverriddenPrefixQueryForCustomFieldType, 
SpatialFilterTest, CursorMarkTest, DistributedMLTComponentTest, 
TestIntervalFaceting, TestHashPartitioner, AddBlockUpdateTest, 
CustomCollectionTest, CheckHdfsIndexTest, TestFiltering, JSONWriterTest, 
UpdateRequestProcessorFactoryTest, TestCharFilters, AliasIntegrationTest, 
TestPerFieldSimilarity, ConvertedLegacyTest, TestRandomDVFaceting, 
TestDistributedSearch]
   [junit4] Completed [342/641 (1!)] on J1 in 43.87s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 61272 lines...]


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