Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1697/
Java: 64bit/jdk-11-ea+5 -XX:+UseCompressedOops -XX:+UseParallelGC

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

Error Message:
IOException occured when talking to server at: 
http://127.0.0.1:41843//collection1

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when 
talking to server at: http://127.0.0.1:41843//collection1
        at 
__randomizedtesting.SeedInfo.seed([E0C7CA938A6B12DA:6893F54924977F22]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:895)
        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:858)
        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:873)
        at 
org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:542)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1034)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        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 
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.base/java.lang.Thread.run(Thread.java:841)
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:41843 failed to 
respond
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
        at 
org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
        at 
org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
        at 
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
        at 
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
        at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
        at 
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
        at 
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
        ... 48 more




Build Log:
[...truncated 13278 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/init-core-data-001
   [junit4]   2> 937035 WARN  
(SUITE-TestDistributedSearch-seed#[E0C7CA938A6B12DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 937035 INFO  
(SUITE-TestDistributedSearch-seed#[E0C7CA938A6B12DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 937036 INFO  
(SUITE-TestDistributedSearch-seed#[E0C7CA938A6B12DA]-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> 937036 INFO  
(SUITE-TestDistributedSearch-seed#[E0C7CA938A6B12DA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 937036 INFO  
(SUITE-TestDistributedSearch-seed#[E0C7CA938A6B12DA]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 937151 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/control/cores/collection1
   [junit4]   2> 937151 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 937155 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 937155 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 937155 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 937155 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5af7c653{/,null,AVAILABLE}
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@60ea0f26{HTTP/1.1,[http/1.1]}{127.0.0.1:36069}
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] o.e.j.s.Server 
Started @937189ms
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=36069, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/control/cores}
   [junit4]   2> 937157 ERROR 
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.4.0
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 937157 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-11T22:33:10.402841Z
   [junit4]   2> 937158 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/control/solr.xml
   [junit4]   2> 937159 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 937159 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 937160 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 937185 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f
   [junit4]   2> 937191 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f
   [junit4]   2> 937191 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f
   [junit4]   2> 937192 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/control/cores
   [junit4]   2> 937192 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 937193 INFO  (coreLoadExecutor-3341-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 937193 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 937211 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 937230 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 937274 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 937298 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 937298 INFO  (coreLoadExecutor-3341-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@9bb94f
   [junit4]   2> 937298 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 937298 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 937299 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=52.9501953125, 
floorSegmentMB=0.9033203125, forceMergeDeletesPctAllowed=6.273116448048512, 
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 937341 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 937341 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 937342 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 937342 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@721c2228[collection1] main]
   [junit4]   2> 937343 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 937362 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 937453 INFO  (coreLoadExecutor-3341-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 937453 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 937454 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 937454 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 937454 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 937454 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 937454 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 937454 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 937455 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 937455 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 937456 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@721c2228[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 937524 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 937525 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T08:27:37+11:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 937525 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 937525 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 937525 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7f9a50b1{/,null,AVAILABLE}
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1ce97b7d{HTTP/1.1,[http/1.1]}{127.0.0.1:35905}
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] o.e.j.s.Server 
Started @937558ms
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=35905, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/shard0/cores}
   [junit4]   2> 937526 ERROR 
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.4.0
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 937526 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-04-11T22:33:10.771886Z
   [junit4]   2> 937527 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 937528 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 937528 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 937536 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 937553 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f
   [junit4]   2> 937559 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f
   [junit4]   2> 937560 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@9bb94f
   [junit4]   2> 937561 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/shard0/cores
   [junit4]   2> 937561 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 937561 INFO  (coreLoadExecutor-3351-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 937561 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 937582 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 937593 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 937653 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 937660 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 937660 INFO  (coreLoadExecutor-3351-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@9bb94f
   [junit4]   2> 937660 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 937660 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 937661 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=26, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=52.9501953125, 
floorSegmentMB=0.9033203125, forceMergeDeletesPctAllowed=6.273116448048512, 
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 937709 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 937710 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 937711 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=46, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 937711 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7542845c[collection1] main]
   [junit4]   2> 937712 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 937732 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 937826 INFO  (coreLoadExecutor-3351-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 937826 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 937827 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 937828 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7542845c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 937828 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 937829 INFO  (qtp921454364-8996) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 937830 INFO  (qtp921454364-8996) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
   [junit4]   2> 937830 INFO  (qtp1648549944-9013) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 937830 INFO  (qtp1648549944-9013) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
   [junit4]   2> 937833 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1]} 0 1
   [junit4]   2> 937834 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1]} 0 1
   [junit4]   2> 937835 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 937836 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 937839 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 937840 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 937841 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 937841 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 937842 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 937843 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 937843 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6]} 0 0
   [junit4]   2> 937844 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6]} 0 0
   [junit4]   2> 937845 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7]} 0 0
   [junit4]   2> 937845 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7]} 0 0
   [junit4]   2> 937846 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8]} 0 0
   [junit4]   2> 937846 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8]} 0 0
   [junit4]   2> 937847 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9]} 0 0
   [junit4]   2> 937847 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9]} 0 0
   [junit4]   2> 937847 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 937847 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14986c1d commitCommandVersion:0
   [junit4]   2> 937850 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@460b4b7c[collection1] main]
   [junit4]   2> 937850 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 937851 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@460b4b7c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C9)))}
   [junit4]   2> 937851 INFO  (qtp921454364-9002) [    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 3
   [junit4]   2> 937851 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 937852 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@dc2066 commitCommandVersion:0
   [junit4]   2> 937854 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ff050c9[collection1] main]
   [junit4]   2> 937854 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 937855 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ff050c9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C9)))}
   [junit4]   2> 937855 INFO  (qtp1648549944-9019) [    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 3
   [junit4]   2> 937856 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10]} 0 0
   [junit4]   2> 937857 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10]} 0 0
   [junit4]   2> 937858 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11]} 0 0
   [junit4]   2> 937858 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11]} 0 0
   [junit4]   2> 937859 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12]} 0 0
   [junit4]   2> 937860 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12]} 0 0
   [junit4]   2> 937861 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13]} 0 0
   [junit4]   2> 937861 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13]} 0 0
   [junit4]   2> 937862 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001]} 0 0
   [junit4]   2> 937863 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001]} 0 0
   [junit4]   2> 937864 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14]} 0 0
   [junit4]   2> 937865 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14]} 0 0
   [junit4]   2> 937865 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15]} 0 0
   [junit4]   2> 937866 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15]} 0 0
   [junit4]   2> 937867 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16]} 0 0
   [junit4]   2> 937867 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16]} 0 0
   [junit4]   2> 937868 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17]} 0 0
   [junit4]   2> 937869 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17]} 0 0
   [junit4]   2> 937869 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100]} 0 0
   [junit4]   2> 937870 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100]} 0 0
   [junit4]   2> 937871 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101]} 0 0
   [junit4]   2> 937871 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101]} 0 0
   [junit4]   2> 937872 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102]} 0 0
   [junit4]   2> 937872 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102]} 0 0
   [junit4]   2> 937873 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103]} 0 0
   [junit4]   2> 937873 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103]} 0 0
   [junit4]   2> 937874 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104]} 0 0
   [junit4]   2> 937875 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104]} 0 0
   [junit4]   2> 937876 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105]} 0 0
   [junit4]   2> 937876 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105]} 0 0
   [junit4]   2> 937877 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106]} 0 0
   [junit4]   2> 937877 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106]} 0 0
   [junit4]   2> 937878 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107]} 0 0
   [junit4]   2> 937879 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107]} 0 0
   [junit4]   2> 937879 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108]} 0 0
   [junit4]   2> 937880 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108]} 0 0
   [junit4]   2> 937880 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109]} 0 0
   [junit4]   2> 937881 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109]} 0 0
   [junit4]   2> 937882 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110]} 0 0
   [junit4]   2> 937882 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110]} 0 0
   [junit4]   2> 937883 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111]} 0 0
   [junit4]   2> 937883 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111]} 0 0
   [junit4]   2> 937884 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112]} 0 0
   [junit4]   2> 937884 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112]} 0 0
   [junit4]   2> 937885 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113]} 0 0
   [junit4]   2> 937886 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113]} 0 0
   [junit4]   2> 937886 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114]} 0 0
   [junit4]   2> 937887 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114]} 0 0
   [junit4]   2> 937887 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115]} 0 0
   [junit4]   2> 937888 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115]} 0 0
   [junit4]   2> 937888 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116]} 0 0
   [junit4]   2> 937889 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116]} 0 0
   [junit4]   2> 937889 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117]} 0 0
   [junit4]   2> 937890 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117]} 0 0
   [junit4]   2> 937890 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118]} 0 0
   [junit4]   2> 937891 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118]} 0 0
   [junit4]   2> 937891 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119]} 0 0
   [junit4]   2> 937892 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119]} 0 0
   [junit4]   2> 937892 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120]} 0 0
   [junit4]   2> 937893 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120]} 0 0
   [junit4]   2> 937893 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121]} 0 0
   [junit4]   2> 937894 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121]} 0 0
   [junit4]   2> 937894 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122]} 0 0
   [junit4]   2> 937895 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122]} 0 0
   [junit4]   2> 937895 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123]} 0 0
   [junit4]   2> 937896 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123]} 0 0
   [junit4]   2> 937896 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124]} 0 0
   [junit4]   2> 937897 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124]} 0 0
   [junit4]   2> 937897 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125]} 0 0
   [junit4]   2> 937897 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125]} 0 0
   [junit4]   2> 937898 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126]} 0 0
   [junit4]   2> 937898 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126]} 0 0
   [junit4]   2> 937899 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127]} 0 0
   [junit4]   2> 937899 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127]} 0 0
   [junit4]   2> 937899 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128]} 0 0
   [junit4]   2> 937900 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128]} 0 0
   [junit4]   2> 937900 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129]} 0 0
   [junit4]   2> 937901 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129]} 0 0
   [junit4]   2> 937901 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130]} 0 0
   [junit4]   2> 937901 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130]} 0 0
   [junit4]   2> 937902 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131]} 0 0
   [junit4]   2> 937902 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131]} 0 0
   [junit4]   2> 937903 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132]} 0 0
   [junit4]   2> 937903 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132]} 0 0
   [junit4]   2> 937903 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133]} 0 0
   [junit4]   2> 937904 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133]} 0 0
   [junit4]   2> 937904 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134]} 0 0
   [junit4]   2> 937905 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134]} 0 0
   [junit4]   2> 937905 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135]} 0 0
   [junit4]   2> 937906 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135]} 0 0
   [junit4]   2> 937906 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136]} 0 0
   [junit4]   2> 937906 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136]} 0 0
   [junit4]   2> 937907 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137]} 0 0
   [junit4]   2> 937907 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137]} 0 0
   [junit4]   2> 937907 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138]} 0 0
   [junit4]   2> 937908 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138]} 0 0
   [junit4]   2> 937908 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139]} 0 0
   [junit4]   2> 937908 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139]} 0 0
   [junit4]   2> 937909 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140]} 0 0
   [junit4]   2> 937909 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140]} 0 0
   [junit4]   2> 937909 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141]} 0 0
   [junit4]   2> 937910 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141]} 0 0
   [junit4]   2> 937910 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142]} 0 0
   [junit4]   2> 937910 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142]} 0 0
   [junit4]   2> 937910 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143]} 0 0
   [junit4]   2> 937911 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143]} 0 0
   [junit4]   2> 937911 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144]} 0 0
   [junit4]   2> 937911 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144]} 0 0
   [junit4]   2> 937912 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145]} 0 0
   [junit4]   2> 937912 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145]} 0 0
   [junit4]   2> 937912 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146]} 0 0
   [junit4]   2> 937912 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146]} 0 0
   [junit4]   2> 937913 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147]} 0 0
   [junit4]   2> 937913 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147]} 0 0
   [junit4]   2> 937914 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148]} 0 0
   [junit4]   2> 937914 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148]} 0 0
   [junit4]   2> 937914 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149]} 0 0
   [junit4]   2> 937915 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149]} 0 0
   [junit4]   2> 937915 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 937915 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14986c1d commitCommandVersion:0
   [junit4]   2> 937919 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2ddef757[collection1] main]
   [junit4]   2> 937920 INFO  
(searcherExecutor-3342-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2ddef757[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C9)
 Uninverting(_1(7.4.0):C59)))}
   [junit4]   2> 937920 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 937920 INFO  (qtp921454364-9002) [    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 5
   [junit4]   2> 937921 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 937921 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@dc2066 commitCommandVersion:0
   [junit4]   2> 937924 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@47afcda0[collection1] main]
   [junit4]   2> 937925 INFO  
(searcherExecutor-3352-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@47afcda0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C9)
 Uninverting(_1(7.4.0):C59)))}
   [junit4]   2> 937925 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 937925 INFO  (qtp1648549944-9019) [    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 4
   [junit4]   2> 937926 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 937926 INFO  (qtp921454364-9000) [    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> 937926 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 937926 INFO  (qtp921454364-9002) [    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> 937928 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 937942 INFO  (qtp1648549944-9018) [    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=[ff01::083]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1523485991175&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 937948 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991175&ids=143,122,1,2,123,124,114,106,107,129&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 937952 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=22
   [junit4]   2> 937953 INFO  (qtp921454364-9002) [    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> 937955 INFO  (qtp1648549944-9013) [    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=http://127.0.0.1:35905//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1523485991200&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 937956 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991200&ids=121,111,12,133,147,16,116,6,140,120&isShard=true&shard.url=http://127.0.0.1:35905//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 937959 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 937960 INFO  (qtp921454364-9000) [    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> 937962 INFO  (qtp1648549944-9013) [    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=http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991206&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 937964 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991206&ids=11,100,101,13,127,1001,139,108,130,10&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 937966 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 937978 INFO  (qtp921454364-9002) [    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> 937983 INFO  (qtp1648549944-9013) [    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=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991226&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 937984 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991226&ids=132,112,145,135,3,4,126,6,138,8&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 937988 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:35905//collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 937989 INFO  (qtp921454364-9000) [    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> 937991 INFO  (qtp1648549944-9013) [    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=http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991236&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 937992 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991236&ids=11,144,5,16,6,138,139,1001,8,119&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 937994 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 937995 INFO  (qtp921454364-9002) [    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> 938010 INFO  (qtp1648549944-9013) [    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::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991241&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938011 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991241&ids=143,122,101,135,114,126,129,140,120,10&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938012 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=16
   [junit4]   2> 938013 INFO  (qtp921454364-9000) [    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> 938014 INFO  (qtp1648549944-9013) [    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:35905//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1523485991259&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938019 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991259&ids=11,121,2,3,136,15,16,115,129,141&isShard=true&shard.url=http://127.0.0.1:35905//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938020 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 938020 INFO  (qtp921454364-9002) [    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> 938023 INFO  (qtp1648549944-9013) [    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:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991267&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938024 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991267&ids=12,111,13,124,103,105,106,9,140,142&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938025 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938026 INFO  (qtp921454364-9000) [    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> 938028 INFO  (qtp1648549944-9013) [    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:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991272&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938030 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991272&ids=132,145,134,146,102,125,136,104,9,131&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938030 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:35905//collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 938031 INFO  (qtp921454364-9002) [    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> 938034 INFO  (qtp1648549944-9013) [    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=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1523485991278&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938034 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991278&ids=121,122,113,14,114,5,6,117,1001,141&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938035 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938037 INFO  (qtp921454364-9000) [    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> 938038 INFO  (qtp1648549944-9013) [    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=[ff01::114]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1523485991283&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938040 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991283&ids=132,111,3,125,147,5,148,149,116,142&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938041 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:35905//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 938042 INFO  (qtp921454364-9002) [    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> 938048 INFO  (qtp1648549944-9013) [    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:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991292&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938049 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991292&ids=11,1,112,146,15,117,8,107,10,131&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938049 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938050 INFO  (qtp921454364-9000) [    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> 938052 INFO  (qtp1648549944-9013) [    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:35905//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1523485991297&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938053 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991297&ids=100,145,123,134,5,17,1001,129,140,131&isShard=true&shard.url=http://127.0.0.1:35905//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938054 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938055 INFO  (qtp921454364-9002) [    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> 938056 INFO  (qtp1648549944-9013) [    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/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991301&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938058 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991301&ids=122,3,147,15,136,126,137,106,109,130&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938059 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:35905//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938060 INFO  (qtp921454364-9000) [    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> 938061 INFO  (qtp1648549944-9013) [    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:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991305&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938062 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991305&ids=121,112,102,4,136,114,103,137,105,106&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938063 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938063 INFO  (qtp921454364-9002) [    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> 938065 INFO  (qtp1648549944-9013) [    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::114]:33332/|http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991309&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938066 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991309&ids=144,122,101,3,127,139,107,108,119,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938067 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:35905//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938067 INFO  (qtp921454364-9000) [    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> 938069 INFO  (qtp1648549944-9013) [    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=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:35905//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1523485991314&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938070 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991314&ids=121,133,146,125,5,104,17,118,129,120&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:35905//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938071 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:35905//collection1|[ff01::083]:33332/&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938072 INFO  (qtp921454364-9002) [    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> 938074 INFO  (qtp1648549944-9013) [    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:35905//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1523485991318&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938075 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991318&ids=110,145,112,13,14,4,126,116,139,141&isShard=true&shard.url=http://127.0.0.1:35905//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938075 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938076 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 938079 INFO  (qtp1648549944-9013) [    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_i_p+desc&shard.url=http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991323&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938080 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991323&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938081 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=a_i_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938083 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 938086 INFO  (qtp1648549944-9013) [    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_i_p,5))+desc&shard.url=http://127.0.0.1:35905//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1523485991330&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938088 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991330&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:35905//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938089 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1|[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 938091 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_p[*+TO+*]&distrib=false&sort=a_i_p+asc&wt=javabin&version=2} 
hits=13 status=0 QTime=0
   [junit4]   2> 938093 INFO  (qtp1648549944-9013) [    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_i_p+asc&shard.url=http://127.0.0.1:35905//collection1|[ff01::213]:33332/&rows=10&version=2&q=a_i_p[*+TO+*]&NOW=1523485991337&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 938094 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_p[*+TO+*]&df=text&distrib=false&shards.purpose=64&NOW=1523485991337&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:35905//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938095 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_i_p[*+TO+*]&shards=http://127.0.0.1:35905//collection1|[ff01::213]:33332/&sort=a_i_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=2
   [junit4]   2> 938096 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i_p+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 938098 INFO  (qtp1648549944-9013) [    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_i_p+asc,+id+desc&shard.url=http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991342&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938098 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1523485991342&ids=11,146,147,15,4,148,16,149,17,6&isShard=true&shard.url=http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938099 INFO  (qtp1648549944-9019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:35905//collection1&sort=a_i_p+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938100 INFO  (qtp921454364-9000) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i_p+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 938101 INFO  (qtp1648549944-9013) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i_p+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1&rows=10&version=2&q=*:*&NOW=1523485991346&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 938102 INFO  (qtp1648549944-9017) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&fl=*,score&shards.purpose=64&NOW=1523485991346&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 938103 INFO  (qtp1648549944-9014) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:35905//collection1&fl=*,score&sort=a_i_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 938104 INFO  (qtp921454364-9002) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QT

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

938A6B12DA]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@44f57be5: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@332bac32
   [junit4]   2> 960764 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 960764 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@778f8dca: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@22750e4
   [junit4]   2> 960765 INFO  (coreCloseExecutor-3454-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1f7df015
   [junit4]   2> 960765 INFO  (coreCloseExecutor-3454-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1f7df015
   [junit4]   2> 960765 INFO  (coreCloseExecutor-3454-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7271214f: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3b322814
   [junit4]   2> 960769 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2da30c87{/,null,UNAVAILABLE}
   [junit4]   2> 960769 INFO  
(TEST-TestDistributedSearch.test-seed#[E0C7CA938A6B12DA]) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=E0C7CA938A6B12DA -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ii -Dtests.timezone=Etc/Greenwich -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   23.7s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 
org.apache.solr.client.solrj.SolrServerException: IOException occured when 
talking to server at: http://127.0.0.1:41843//collection1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E0C7CA938A6B12DA:6893F54924977F22]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:657)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:895)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:858)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:873)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:542)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1034)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:564)
   [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.base/java.lang.Thread.run(Thread.java:841)
   [junit4]    > Caused by: org.apache.http.NoHttpResponseException: 
127.0.0.1:41843 failed to respond
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141)
   [junit4]    >        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
   [junit4]    >        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
   [junit4]    >        at 
org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
   [junit4]    >        at 
org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
   [junit4]    >        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
   [junit4]    >        at 
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
   [junit4]    >        at 
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
   [junit4]    >        at 
org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
   [junit4]    >        at 
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
   [junit4]    >        at 
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
   [junit4]    >        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
   [junit4]    >        ... 48 more
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_E0C7CA938A6B12DA-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=true): {}, locale=ii, timezone=Etc/Greenwich
   [junit4]   2> NOTE: Linux 4.13.0-37-generic amd64/Oracle Corporation 11-ea 
(64-bit)/cpus=8,threads=2,free=46788456,total=533725184
   [junit4]   2> NOTE: All tests run in this JVM: 
[AutoAddReplicasIntegrationTest, TestLuceneMatchVersion, 
DefaultValueUpdateProcessorTest, BlockCacheTest, DeleteNodeTest, 
XmlUpdateRequestHandlerTest, TermVectorComponentDistributedTest, 
TestDirectoryFactory, RequestHandlersTest, SolrRequestParserTest, 
DocExpirationUpdateProcessorFactoryTest, TestCollectionAPI, 
TestNamedUpdateProcessors, TestSearcherReuse, TestRuleBasedAuthorizationPlugin, 
CdcrBootstrapTest, TestSolrQueryParser, CollectionsAPIDistributedZkTest, 
MBeansHandlerTest, AutoscalingHistoryHandlerTest, 
TestEmbeddedSolrServerConstructors, DistributedQueryElevationComponentTest, 
TestQueryWrapperFilter, CreateRoutedAliasTest, 
ClassificationUpdateProcessorIntegrationTest, TestConfigOverlay, 
HttpPartitionTest, TestCSVLoader, TestCoreBackup, 
NodeLostTriggerIntegrationTest, JavabinLoaderTest, TestJoin, 
TestRetrieveFieldsOptimizer, SpellCheckCollatorTest, TestSchemaResource, 
TestFilteredDocIdSet, SolrCloudReportersTest, TestInitQParser, BJQParserTest, 
TestUseDocValuesAsStored2, RecoveryAfterSoftCommitTest, 
TestHttpShardHandlerFactory, HttpTriggerListenerTest, 
ShufflingReplicaListTransformerTest, TestSolrCloudWithKerberosAlt, 
DistributedFacetExistsSmallTest, TestManagedSchemaThreadSafety, TestConfig, 
SolrIndexConfigTest, SparseHLLTest, TestCollectionAPIs, 
TestConfigSetsAPIExclusivity, TestUninvertingReader, TestRandomFaceting, 
TestMissingGroups, TestSchemalessBufferedUpdates, TestCloudDeleteByQuery, 
TestGenericDistributedQueue, RollingRestartTest, HdfsDirectoryTest, 
MetricTriggerTest, RestoreTriggerStateTest, HdfsChaosMonkeySafeLeaderTest, 
TokenizerChainTest, SolrCoreCheckLockOnStartupTest, SolrJmxReporterCloudTest, 
TestFileDictionaryLookup, ManagedSchemaRoundRobinCloudTest, 
TestLocalFSCloudBackupRestore, CurrencyRangeFacetCloudTest, TestComponentsName, 
ConfigSetsAPITest, MergeStrategyTest, MetricsHandlerTest, 
HdfsBasicDistributedZk2Test, TestHashPartitioner, CdcrUpdateLogTest, 
DistribJoinFromCollectionTest, HdfsDirectoryFactoryTest, 
TestExtendedDismaxParser, TestOmitPositions, ZkFailoverTest, EchoParamsTest, 
TestRandomRequestDistribution, AliasIntegrationTest, TestXmlQParser, 
SuggestComponentTest, TestCloudPivotFacet, DirectUpdateHandlerTest, 
ConnectionReuseTest, FullSolrCloudDistribCmdsTest, TestReRankQParserPlugin, 
TestPullReplica, DocValuesNotIndexedTest, CoreAdminRequestStatusTest, 
TestDistributedSearch]
   [junit4] Completed [295/801 (1!)] on J0 in 23.75s, 1 test, 1 error <<< 
FAILURES!

[...truncated 44599 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1697/consoleText

[repro] Revision: c4a472a9415fa7f3b1270eeb4e72fb5772bdf961

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line:  ant test  -Dtestcase=TestDistributedSearch 
-Dtests.method=test -Dtests.seed=E0C7CA938A6B12DA -Dtests.multiplier=3 
-Dtests.slow=true -Dtests.locale=ii -Dtests.timezone=Etc/Greenwich 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       TestDistributedSearch
[repro] ant compile-test

[...truncated 3317 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestDistributedSearch" -Dtests.showOutput=onerror 
"-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" 
-Dtests.seed=E0C7CA938A6B12DA -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ii -Dtests.timezone=Etc/Greenwich -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[...truncated 79 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.TestDistributedSearch
[repro] Exiting with code 0

[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to