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

[Policeman Jenkins Server]

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

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

Error Message:
Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded  at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
  at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
  at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
  at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 
 at java.lang.Thread.run(Thread.java:748) ,time=2}

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
,time=2}
        at 
__randomizedtesting.SeedInfo.seed([79447D1405891A83:F11042CEAB75777B]: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:1191)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12792 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 1469724 INFO  
(SUITE-TestDistributedSearch-seed#[79447D1405891A83]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/init-core-data-001
   [junit4]   2> 1469725 INFO  
(SUITE-TestDistributedSearch-seed#[79447D1405891A83]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1469726 INFO  
(SUITE-TestDistributedSearch-seed#[79447D1405891A83]-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> 1469726 INFO  
(SUITE-TestDistributedSearch-seed#[79447D1405891A83]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1469975 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1469977 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T09:27:37+12:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1469977 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1469978 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1469978 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1469978 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1822d684{/,null,AVAILABLE}
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7f617113{HTTP/1.1,[http/1.1]}{127.0.0.1:46742}
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] o.e.j.s.Server 
Started @1472934ms
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=46742, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/control/cores}
   [junit4]   2> 1469989 ERROR 
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1469989 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-12T04:27:53.394Z
   [junit4]   2> 1469990 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/control/solr.xml
   [junit4]   2> 1469995 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1469995 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1469996 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1470047 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470057 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470057 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470059 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/control/cores
   [junit4]   2> 1470059 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1470060 INFO  (coreLoadExecutor-3331-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1470062 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1470099 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1470118 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1470215 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1470224 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1470225 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470225 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1470225 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1470226 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=23, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=91.1806640625, 
floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=29.691569415880338, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.2749936036211388
   [junit4]   2> 1470276 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1470276 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1470277 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1470277 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1470278 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1780082532, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1470278 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2f689e69[collection1] main]
   [junit4]   2> 1470279 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1470284 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1470304 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1470304 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1470304 INFO  (coreLoadExecutor-3331-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592167942019088384
   [junit4]   2> 1470305 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1470305 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1470305 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1470305 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1470305 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1470306 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1470306 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1470306 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1470308 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2f689e69[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1470495 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1470496 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T09:27:37+12:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1470497 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1470498 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1470498 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1470498 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@73c3324f{/,null,AVAILABLE}
   [junit4]   2> 1470498 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@777a21c8{HTTP/1.1,[http/1.1]}{127.0.0.1:35567}
   [junit4]   2> 1470498 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] o.e.j.s.Server 
Started @1473444ms
   [junit4]   2> 1470498 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=35567, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/shard0/cores}
   [junit4]   2> 1470499 ERROR 
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1470499 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 1470499 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1470499 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1470499 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-12T04:27:53.904Z
   [junit4]   2> 1470499 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1470502 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1470502 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1470503 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1470544 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470552 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470552 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470554 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/shard0/cores
   [junit4]   2> 1470554 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1470555 INFO  (coreLoadExecutor-3342-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1470560 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1470610 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1470622 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1470683 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1470690 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1470690 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@75b9286d
   [junit4]   2> 1470690 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1470691 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1470692 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=23, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=91.1806640625, 
floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=29.691569415880338, 
segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.2749936036211388
   [junit4]   2> 1470762 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1470762 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1470764 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1470764 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1470765 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1780082532, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1470766 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@27255dc3[collection1] main]
   [junit4]   2> 1470766 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1470771 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1470788 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1470789 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1470789 INFO  (coreLoadExecutor-3342-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592167942527647744
   [junit4]   2> 1470789 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1470789 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1470789 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1470790 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1470790 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1470790 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1470791 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1470791 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1470791 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1470793 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@27255dc3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1470796 INFO  (qtp1013837566-10164) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592167942532890624)} 0 1
   [junit4]   2> 1470800 INFO  (qtp1043757549-10181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592167942536036352)} 0 2
   [junit4]   2> 1470807 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1592167942540230656)]} 0 5
   [junit4]   2> 1470827 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1592167942547570688)]} 0 19
   [junit4]   2> 1470828 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1592167942567493632)]} 0 0
   [junit4]   2> 1470830 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1592167942569590784)]} 0 0
   [junit4]   2> 1470831 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1592167942570639360)]} 0 0
   [junit4]   2> 1470832 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1592167942572736512)]} 0 0
   [junit4]   2> 1470833 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1592167942572736512)]} 0 0
   [junit4]   2> 1470833 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1592167942573785088)]} 0 0
   [junit4]   2> 1470834 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1592167942574833664)]} 0 0
   [junit4]   2> 1470835 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1592167942575882240)]} 0 0
   [junit4]   2> 1470837 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1592167942577979392)]} 0 0
   [junit4]   2> 1470838 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1592167942579027968)]} 0 0
   [junit4]   2> 1470839 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1592167942580076544)]} 0 0
   [junit4]   2> 1470840 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1592167942581125120)]} 0 0
   [junit4]   2> 1470842 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1592167942583222272)]} 0 0
   [junit4]   2> 1470843 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1592167942584270848)]} 0 0
   [junit4]   2> 1470843 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1592167942584270848)]} 0 0
   [junit4]   2> 1470843 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1592167942584270849)]} 0 0
   [junit4]   2> 1470843 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592167942584270849,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1470843 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@76883e08 
commitCommandVersion:1592167942584270849
   [junit4]   2> 1470855 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@749a891a[collection1] main]
   [junit4]   2> 1470856 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1470857 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@749a891a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
   [junit4]   2> 1470857 INFO  (qtp1013837566-10168) [    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> 1470858 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592167942599999488,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1470858 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1bfd33bc 
commitCommandVersion:1592167942599999488
   [junit4]   2> 1470865 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d227a95[collection1] main]
   [junit4]   2> 1470866 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1470867 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d227a95[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
   [junit4]   2> 1470867 INFO  (qtp1043757549-10187) [    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> 1470869 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1592167942609436672)]} 0 1
   [junit4]   2> 1470871 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1592167942611533824)]} 0 1
   [junit4]   2> 1470872 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1592167942613630976)]} 0 1
   [junit4]   2> 1470874 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1592167942615728128)]} 0 1
   [junit4]   2> 1470875 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1592167942616776704)]} 0 0
   [junit4]   2> 1470876 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1592167942617825280)]} 0 0
   [junit4]   2> 1470876 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1592167942618873856)]} 0 0
   [junit4]   2> 1470877 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1592167942619922432)]} 0 0
   [junit4]   2> 1470878 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1592167942620971008)]} 0 0
   [junit4]   2> 1470879 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1592167942622019584)]} 0 0
   [junit4]   2> 1470880 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1592167942623068160)]} 0 0
   [junit4]   2> 1470881 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1592167942624116736)]} 0 0
   [junit4]   2> 1470882 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1592167942625165312)]} 0 0
   [junit4]   2> 1470883 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1592167942626213888)]} 0 0
   [junit4]   2> 1470883 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1592167942626213888)]} 0 0
   [junit4]   2> 1470884 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1592167942627262464)]} 0 0
   [junit4]   2> 1470885 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1592167942628311040)]} 0 0
   [junit4]   2> 1470886 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1592167942628311040)]} 0 0
   [junit4]   2> 1470886 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1592167942629359616)]} 0 0
   [junit4]   2> 1470887 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1592167942630408192)]} 0 0
   [junit4]   2> 1470888 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1592167942631456768)]} 0 0
   [junit4]   2> 1470888 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1592167942631456768)]} 0 0
   [junit4]   2> 1470889 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1592167942632505344)]} 0 0
   [junit4]   2> 1470890 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1592167942632505344)]} 0 0
   [junit4]   2> 1470890 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1592167942633553920)]} 0 0
   [junit4]   2> 1470891 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1592167942634602496)]} 0 0
   [junit4]   2> 1470891 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1592167942634602496)]} 0 0
   [junit4]   2> 1470892 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1592167942635651072)]} 0 0
   [junit4]   2> 1470892 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1592167942635651072)]} 0 0
   [junit4]   2> 1470893 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1592167942636699648)]} 0 0
   [junit4]   2> 1470893 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1592167942636699648)]} 0 0
   [junit4]   2> 1470894 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1592167942637748224)]} 0 0
   [junit4]   2> 1470894 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1592167942637748224)]} 0 0
   [junit4]   2> 1470895 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1592167942638796800)]} 0 0
   [junit4]   2> 1470896 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1592167942638796800)]} 0 0
   [junit4]   2> 1470896 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1592167942639845376)]} 0 0
   [junit4]   2> 1470897 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1592167942640893952)]} 0 0
   [junit4]   2> 1470897 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1592167942640893952)]} 0 0
   [junit4]   2> 1470898 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1592167942641942528)]} 0 0
   [junit4]   2> 1470899 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1592167942641942528)]} 0 0
   [junit4]   2> 1470899 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1592167942642991104)]} 0 0
   [junit4]   2> 1470900 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1592167942644039680)]} 0 0
   [junit4]   2> 1470900 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1592167942644039680)]} 0 0
   [junit4]   2> 1470901 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1592167942645088256)]} 0 0
   [junit4]   2> 1470902 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1592167942646136832)]} 0 0
   [junit4]   2> 1470903 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1592167942646136832)]} 0 0
   [junit4]   2> 1470903 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1592167942647185408)]} 0 0
   [junit4]   2> 1470904 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1592167942648233984)]} 0 0
   [junit4]   2> 1470905 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1592167942649282560)]} 0 0
   [junit4]   2> 1470906 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1592167942650331136)]} 0 0
   [junit4]   2> 1470907 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1592167942650331136)]} 0 0
   [junit4]   2> 1470908 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1592167942651379712)]} 0 0
   [junit4]   2> 1470908 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1592167942652428288)]} 0 0
   [junit4]   2> 1470909 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1592167942653476864)]} 0 0
   [junit4]   2> 1470910 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1592167942654525440)]} 0 0
   [junit4]   2> 1470911 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1592167942654525440)]} 0 0
   [junit4]   2> 1470911 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1592167942655574016)]} 0 0
   [junit4]   2> 1470912 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1592167942656622592)]} 0 0
   [junit4]   2> 1470912 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1592167942656622592)]} 0 0
   [junit4]   2> 1470913 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1592167942657671168)]} 0 0
   [junit4]   2> 1470913 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1592167942657671168)]} 0 0
   [junit4]   2> 1470914 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1592167942658719744)]} 0 0
   [junit4]   2> 1470915 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1592167942658719744)]} 0 0
   [junit4]   2> 1470915 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1592167942659768320)]} 0 0
   [junit4]   2> 1470916 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1592167942660816896)]} 0 0
   [junit4]   2> 1470917 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1592167942661865472)]} 0 0
   [junit4]   2> 1470917 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1592167942661865472)]} 0 0
   [junit4]   2> 1470918 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1592167942662914048)]} 0 0
   [junit4]   2> 1470919 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1592167942662914048)]} 0 0
   [junit4]   2> 1470919 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1592167942663962624)]} 0 0
   [junit4]   2> 1470920 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1592167942663962624)]} 0 0
   [junit4]   2> 1470920 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1592167942665011200)]} 0 0
   [junit4]   2> 1470921 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1592167942666059776)]} 0 0
   [junit4]   2> 1470921 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1592167942666059776)]} 0 0
   [junit4]   2> 1470922 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1592167942667108352)]} 0 0
   [junit4]   2> 1470922 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1592167942667108352)]} 0 0
   [junit4]   2> 1470923 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1592167942668156928)]} 0 0
   [junit4]   2> 1470924 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1592167942669205504)]} 0 0
   [junit4]   2> 1470925 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1592167942669205504)]} 0 0
   [junit4]   2> 1470925 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1592167942670254080)]} 0 0
   [junit4]   2> 1470926 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1592167942671302656)]} 0 0
   [junit4]   2> 1470926 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1592167942671302656)]} 0 0
   [junit4]   2> 1470927 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1592167942672351232)]} 0 0
   [junit4]   2> 1470928 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1592167942672351232)]} 0 0
   [junit4]   2> 1470928 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1592167942673399808)]} 0 0
   [junit4]   2> 1470929 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1592167942674448384)]} 0 0
   [junit4]   2> 1470930 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1592167942674448384)]} 0 0
   [junit4]   2> 1470930 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1592167942675496960)]} 0 0
   [junit4]   2> 1470931 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1592167942676545536)]} 0 0
   [junit4]   2> 1470932 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1592167942676545536)]} 0 0
   [junit4]   2> 1470932 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1592167942677594112)]} 0 0
   [junit4]   2> 1470933 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1592167942678642688)]} 0 0
   [junit4]   2> 1470933 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1592167942678642688)]} 0 0
   [junit4]   2> 1470934 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1592167942679691264)]} 0 0
   [junit4]   2> 1470935 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1592167942680739840)]} 0 0
   [junit4]   2> 1470936 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1592167942680739840)]} 0 0
   [junit4]   2> 1470936 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1592167942681788416)]} 0 0
   [junit4]   2> 1470937 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1592167942682836992)]} 0 0
   [junit4]   2> 1470938 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1592167942683885568)]} 0 0
   [junit4]   2> 1470939 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1592167942683885568)]} 0 0
   [junit4]   2> 1470939 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1592167942684934144)]} 0 0
   [junit4]   2> 1470940 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1592167942685982720)]} 0 0
   [junit4]   2> 1470941 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1592167942685982720)]} 0 0
   [junit4]   2> 1470941 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1592167942687031296)]} 0 0
   [junit4]   2> 1470942 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1592167942688079872)]} 0 0
   [junit4]   2> 1470943 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1592167942688079872)]} 0 0
   [junit4]   2> 1470943 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1592167942689128448)]} 0 0
   [junit4]   2> 1470944 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1592167942690177024)]} 0 0
   [junit4]   2> 1470944 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1592167942690177024)]} 0 0
   [junit4]   2> 1470945 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1592167942691225600)]} 0 0
   [junit4]   2> 1470945 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1592167942691225600)]} 0 0
   [junit4]   2> 1470946 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1592167942692274176)]} 0 0
   [junit4]   2> 1470947 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1592167942692274176)]} 0 0
   [junit4]   2> 1470947 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1592167942693322752)]} 0 0
   [junit4]   2> 1470948 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1592167942693322752)]} 0 0
   [junit4]   2> 1470948 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1592167942694371328)]} 0 0
   [junit4]   2> 1470949 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1592167942695419904)]} 0 0
   [junit4]   2> 1470950 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1592167942696468480)]} 0 0
   [junit4]   2> 1470950 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592167942696468480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1470950 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@76883e08 
commitCommandVersion:1592167942696468480
   [junit4]   2> 1470960 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@41fe23ba[collection1] main]
   [junit4]   2> 1470960 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1470962 INFO  
(searcherExecutor-3332-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@41fe23ba[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)
 Uninverting(_1(8.0.0):c59)))}
   [junit4]   2> 1470962 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 1470963 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592167942710099968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1470963 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1bfd33bc 
commitCommandVersion:1592167942710099968
   [junit4]   2> 1470970 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@65b7d242[collection1] main]
   [junit4]   2> 1470971 INFO  (qtp1043757549-10187) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1470973 INFO  
(searcherExecutor-3343-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@65b7d242[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)
 Uninverting(_1(8.0.0):c59)))}
   [junit4]   2> 1470973 INFO  (qtp1043757549-10187) [    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> 1470974 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1470974 INFO  (qtp1013837566-10166) [    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> 1470975 INFO  (qtp1013837566-10168) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1470975 INFO  (qtp1013837566-10168) [    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> 1470977 INFO  (qtp1013837566-10166) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1470983 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674385&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1470986 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674385&ids=11,100,101,113,126,104,1001,141,131,10&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 1470995 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=14
   [junit4]   2> 1470996 INFO  (qtp1013837566-10168) [    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> 1471012 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674402&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471014 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674402&ids=133,111,145,2,102,15,138,139,8,118&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471021 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:35567//collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=23
   [junit4]   2> 1471022 INFO  (qtp1013837566-10166) [    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> 1471024 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674429&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471026 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674429&ids=121,144,111,1,134,5,17,128,9,140&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471033 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:35567//collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 1471034 INFO  (qtp1013837566-10168) [    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> 1471035 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:35567//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518409674440&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471040 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674440&ids=11,123,13,101,14,113,124,147,105,149&isShard=true&shard.url=http://127.0.0.1:35567//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=4
   [junit4]   2> 1471047 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 1471049 INFO  (qtp1013837566-10166) [    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> 1471051 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674455&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471052 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674455&ids=132,100,12,133,147,125,17,127,117,120&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471055 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:35567//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1471056 INFO  (qtp1013837566-10168) [    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> 1471058 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1518409674462&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471059 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674462&ids=145,113,114,128,7,118,129,9,108,140&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471060 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:35567//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1471061 INFO  (qtp1013837566-10166) [    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> 1471063 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674467&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471064 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674467&ids=143,101,114,136,139,8,129,107,130,142&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471065 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471065 INFO  (qtp1013837566-10168) [    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> 1471067 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:35567//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1518409674471&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471069 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674471&ids=110,112,134,2,5,16,106,109,140,131&isShard=true&shard.url=http://127.0.0.1:35567//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471070 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1471071 INFO  (qtp1013837566-10166) [    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> 1471072 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518409674476&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471074 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674476&ids=132,12,2,3,115,127,128,7,109,130&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471075 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:35567//collection1|[ff01::114]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1471075 INFO  (qtp1013837566-10168) [    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> 1471077 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:35567//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518409674482&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471078 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674482&ids=123,145,134,102,17,138,107,9,141,120&isShard=true&shard.url=http://127.0.0.1:35567//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471079 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471080 INFO  (qtp1013837566-10166) [    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> 1471081 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:35567//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518409674486&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471082 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674486&ids=110,3,135,137,117,7,139,9,108,142&isShard=true&shard.url=http://127.0.0.1:35567//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471083 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1|[ff01::114]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471083 INFO  (qtp1013837566-10168) [    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> 1471085 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:35567//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518409674489&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471086 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674489&ids=144,13,146,114,5,128,1001,109,130,10&isShard=true&shard.url=http://127.0.0.1:35567//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471087 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1|[ff01::114]:33332/&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1471088 INFO  (qtp1013837566-10166) [    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> 1471090 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674495&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471092 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674495&ids=143,100,112,146,114,15,104,17,130,120&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471092 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471093 INFO  (qtp1013837566-10168) [    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> 1471094 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674499&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471095 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674499&ids=110,145,113,135,4,126,149,128,119,109&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471096 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:35567//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1471096 INFO  (qtp1013837566-10166) [    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> 1471098 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674502&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471098 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674502&ids=133,100,12,101,3,14,137,104,130,10&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471099 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1471100 INFO  (qtp1013837566-10168) [    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> 1471101 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:35567//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1518409674505&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471102 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674505&ids=144,2,124,146,125,136,148,117,109,140&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:35567//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471102 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:35567//collection1|[ff01::083]:33332/&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471103 INFO  (qtp1013837566-10166) [    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> 1471105 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674509&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471105 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674509&ids=2,102,124,146,14,114,126,129,107,119&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471106 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1471107 INFO  (qtp1013837566-10168) [    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> 1471108 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674512&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471109 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674512&ids=3,135,136,15,16,148,138,105,149,108&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471110 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471111 INFO  (qtp1013837566-10166) [    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> 1471113 INFO  (qtp1043757549-10188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674517&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1471114 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674517&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:35567//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1471115 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35567//collection1&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1471119 INFO  (qtp1013837566-10168) [    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=3
   [junit4]   2> 1471124 INFO  (qtp1043757549-10184) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:35567//collection1&rows=10&version=2&q=*:*&NOW=1518409674524&isShard=true&wt=javabin}
 hits=68 status=0 QTime=4
   [junit4]   2> 1471125 INFO  (qtp1043757549-10185) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518409674524&ids=1,12,2,13,3,5,7,

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

   2> 1497908 INFO  (TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [ 
   ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1617021645
   [junit4]   2> 1497908 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1497908 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ce20dc7: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@607c5783
   [junit4]   2> 1497916 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1497916 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@147de26d: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@2d099855
   [junit4]   2> 1497920 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1497920 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@193e3820: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@2936092c
   [junit4]   2> 1497922 INFO  (coreCloseExecutor-3389-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@15e046b2
   [junit4]   2> 1497922 INFO  (coreCloseExecutor-3389-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=367019698
   [junit4]   2> 1497922 INFO  (coreCloseExecutor-3389-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4ba54f58: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@735722c2
   [junit4]   2> 1497932 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6c745e1{/,null,UNAVAILABLE}
   [junit4]   2> 1497932 INFO  
(TEST-TestDistributedSearch.test-seed#[79447D1405891A83]) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=79447D1405891A83 -Dtests.slow=true -Dtests.locale=fi-FI 
-Dtests.timezone=America/Rio_Branco -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 28.2s 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: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > ,time=2}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([79447D1405891A83:F11042CEAB75777B]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1191)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.TestDistributedSearch_79447D1405891A83-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{severity=PostingsFormat(name=MockRandom), 
foo_sev_enum=PostingsFormat(name=Direct), 
SubjectTerms_mfacet=PostingsFormat(name=Memory), 
multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 rnd_b=PostingsFormat(name=Direct), a_s=PostingsFormat(name=MockRandom), 
oddField_s=PostingsFormat(name=MockRandom), 
lowerfilt1and2=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 a_t=PostingsFormat(name=Memory), foo_b=PostingsFormat(name=MockRandom), 
lowerfilt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 id=PostingsFormat(name=Direct), text=PostingsFormat(name=MockRandom)}, 
docValues:{other_tl1=DocValuesFormat(name=Lucene70), 
range_facet_l_dv=DocValuesFormat(name=Direct), 
n_l1=DocValuesFormat(name=Lucene70), a_n_tdt=DocValuesFormat(name=Direct), 
intDefault=DocValuesFormat(name=Lucene70), 
n_td1=DocValuesFormat(name=Lucene70), n_d1=DocValuesFormat(name=Lucene70), 
range_facet_l=DocValuesFormat(name=Lucene70), 
n_f1=DocValuesFormat(name=Lucene70), n_tl1=DocValuesFormat(name=Lucene70), 
n_tf1=DocValuesFormat(name=Lucene70), 
intDvoDefault=DocValuesFormat(name=Asserting), 
timestamp=DocValuesFormat(name=Lucene70), 
severity=DocValuesFormat(name=Asserting), 
foo_sev_enum=DocValuesFormat(name=Direct), 
n_dt1=DocValuesFormat(name=Lucene70), a_i1=DocValuesFormat(name=Lucene70), 
b_n_tdt=DocValuesFormat(name=Lucene70), n_ti1=DocValuesFormat(name=Direct), 
_version_=DocValuesFormat(name=Lucene70), 
n_tdt1=DocValuesFormat(name=Lucene70), id_i1=DocValuesFormat(name=Lucene70), 
foo_d=DocValuesFormat(name=Direct), 
range_facet_i_dv=DocValuesFormat(name=Lucene70), 
foo_f=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1178, 
maxMBSortInHeap=6.312989121396517, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@186f87a6),
 locale=fi-FI, timezone=America/Rio_Branco
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_152 
(64-bit)/cpus=3,threads=1,free=54772656,total=393138176
   [junit4]   2> NOTE: All tests run in this JVM: [EchoParamsTest, 
TestDefaultStatsCache, InfixSuggestersTest, TestFieldCacheWithThreads, 
LargeFieldTest, TermVectorComponentTest, DeleteLastCustomShardedReplicaTest, 
TestScoreJoinQPNoScore, LoggingHandlerTest, BaseCdcrDistributedZkTest, 
ClusterStateTest, HdfsNNFailoverTest, TestManagedSchemaAPI, TestCrossCoreJoin, 
TestSolrCLIRunExample, DistributedFacetPivotLongTailTest, 
CurrencyRangeFacetCloudTest, MoveReplicaHDFSTest, MetricUtilsTest, 
SearchHandlerTest, V2StandaloneTest, SaslZkACLProviderTest, 
TestEmbeddedSolrServerConstructors, TestTlogReplica, DocValuesMultiTest, 
SolrCmdDistributorTest, TestAnalyzeInfixSuggestions, 
TestAuthorizationFramework, CdcrReplicationHandlerTest, 
PreAnalyzedFieldManagedSchemaCloudTest, TestJettySolrRunner, OverseerTest, 
TestLeaderInitiatedRecoveryThread, TestHighFrequencyDictionaryFactory, 
BigEndianAscendingWordDeserializerTest, ClusterStateUpdateTest, 
CreateRoutedAliasTest, SystemInfoHandlerTest, CollectionReloadTest, 
NoCacheHeaderTest, TestSubQueryTransformerCrossCore, StatsReloadRaceTest, 
TestShardHandlerFactory, HighlighterConfigTest, WrapperMergePolicyFactoryTest, 
SmileWriterTest, TestCollectionAPIs, BasicDistributedZk2Test, 
DistributedIntervalFacetingTest, MetricTriggerTest, 
HdfsWriteToMultipleCollectionsTest, TestHalfAndHalfDocValues, 
DeleteReplicaTest, BadIndexSchemaTest, TestSystemCollAutoCreate, 
MetricsConfigTest, TestHttpShardHandlerFactory, TestHdfsBackupRestoreCore, 
TestStressInPlaceUpdates, CSVRequestHandlerTest, NumberUtilsTest, 
SoftAutoCommitTest, TestCustomDocTransformer, CoreAdminCreateDiscoverTest, 
TestTrieFacet, SpellPossibilityIteratorTest, TestLegacyField, 
TestBulkSchemaConcurrent, TestInfoStreamLogging, 
TestRuleBasedAuthorizationPlugin, TestDynamicFieldResource, 
FileBasedSpellCheckerTest, TestConfigSetsAPIZkFailure, 
HdfsCollectionsAPIDistributedZkTest, SpellCheckCollatorTest, 
TestHashQParserPlugin, TestFastLRUCache, TestSchemalessBufferedUpdates, 
TestLegacyNumericUtils, TestPrepRecovery, SolrCoreCheckLockOnStartupTest, 
TriggerIntegrationTest, TestExceedMaxTermLength, TestChildDocTransformer, 
TestFuzzyAnalyzedSuggestions, TestJsonRequest, BlockDirectoryTest, 
TestHashPartitioner, TestStressLucene, TestDistributedSearch]
   [junit4] Completed [242/776 (1!)] on J1 in 28.23s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 50250 lines...]

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