[JENKINS] Lucene-Solr-Tests-7.x - Build # 86 - Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/86/

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

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

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:748)
,time=2}
        at 
__randomizedtesting.SeedInfo.seed([9BA433AEAB40BDEA:13F00C7405BCD012]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1191)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12037 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> 1087141 INFO  
(SUITE-TestDistributedSearch-seed#[9BA433AEAB40BDEA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/init-core-data-001
   [junit4]   2> 1087142 WARN  
(SUITE-TestDistributedSearch-seed#[9BA433AEAB40BDEA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1087142 INFO  
(SUITE-TestDistributedSearch-seed#[9BA433AEAB40BDEA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1087143 INFO  
(SUITE-TestDistributedSearch-seed#[9BA433AEAB40BDEA]-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> 1087144 INFO  
(SUITE-TestDistributedSearch-seed#[9BA433AEAB40BDEA]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: 
/ww_ki/i
   [junit4]   2> 1087463 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1087464 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 1087477 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@724c911d{/ww_ki/i,null,AVAILABLE}
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@d5f3859{HTTP/1.1,[http/1.1]}{127.0.0.1:52866}
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] o.e.j.s.Server 
Started @1090547ms
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ww_ki/i, 
hostPort=52866, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/control/cores}
   [junit4]   2> 1087479 ERROR 
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1087479 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-22T06:51:55.498Z
   [junit4]   2> 1087480 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/control/solr.xml
   [junit4]   2> 1087496 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1087554 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78
   [junit4]   2> 1087566 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78
   [junit4]   2> 1087566 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78
   [junit4]   2> 1087568 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/control/cores
   [junit4]   2> 1087569 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1087570 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1087629 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1087652 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1087809 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1087823 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1087823 INFO  (coreLoadExecutor-4065-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@7385ed78
   [junit4]   2> 1087823 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1087823 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1087825 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=53.62109375, 
floorSegmentMB=0.890625, forceMergeDeletesPctAllowed=9.16800763543115, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1087956 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1087956 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1087957 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1087957 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1087958 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=45.4658203125, 
floorSegmentMB=1.982421875, forceMergeDeletesPctAllowed=25.759169484147417, 
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1087959 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2ed6b930[collection1] main]
   [junit4]   2> 1087960 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1088021 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1088095 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1088096 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1088096 INFO  (coreLoadExecutor-4065-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1573604626126602240
   [junit4]   2> 1088096 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1088096 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1088096 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1088096 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1088097 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1088097 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1088097 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1088097 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1088099 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2ed6b930[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1088379 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1088380 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 1088382 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@47dbcff4{/ww_ki/i,null,AVAILABLE}
   [junit4]   2> 1088383 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7574f5fe{HTTP/1.1,[http/1.1]}{127.0.0.1:48210}
   [junit4]   2> 1088383 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] o.e.j.s.Server 
Started @1091451ms
   [junit4]   2> 1088383 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ww_ki/i, 
hostPort=48210, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/shard0/cores}
   [junit4]   2> 1088383 ERROR 
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1088383 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 1088383 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1088384 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1088384 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-22T06:51:56.403Z
   [junit4]   2> 1088384 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1088392 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1088456 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78
   [junit4]   2> 1088463 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78
   [junit4]   2> 1088463 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7385ed78
   [junit4]   2> 1088466 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/shard0/cores
   [junit4]   2> 1088466 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1088467 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1088509 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1088573 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1088869 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1088877 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1088878 INFO  (coreLoadExecutor-4076-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@7385ed78
   [junit4]   2> 1088878 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1088878 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1088880 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=53.62109375, 
floorSegmentMB=0.890625, forceMergeDeletesPctAllowed=9.16800763543115, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1089038 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1089038 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1089040 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1089040 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1089046 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=14, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=45.4658203125, 
floorSegmentMB=1.982421875, forceMergeDeletesPctAllowed=25.759169484147417, 
segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1089046 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c48261f[collection1] 
main]
   [junit4]   2> 1089047 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1089063 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1089091 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1089092 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1089092 INFO  (coreLoadExecutor-4076-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1573604627170983936
   [junit4]   2> 1089092 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1089092 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1089092 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1089092 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1089093 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1089093 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1089093 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1089093 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1089095 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@c48261f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1089526 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1089540 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1573604627634454528)} 0 5
   [junit4]   2> 1089576 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1573604627668008960)} 0 10
   [junit4]   2> 1089579 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[1 (1573604627679543296)]} 0 2
   [junit4]   2> 1089583 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[1 (1573604627682689024)]} 0 3
   [junit4]   2> 1089588 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[2 (1573604627685834752)]} 0 4
   [junit4]   2> 1089594 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[2 (1573604627691077632)]} 0 5
   [junit4]   2> 1089595 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[3 (1573604627698417664)]} 0 0
   [junit4]   2> 1089596 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[3 (1573604627699466240)]} 0 0
   [junit4]   2> 1089597 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[4 (1573604627700514816)]} 0 0
   [junit4]   2> 1089606 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[4 (1573604627705757696)]} 0 3
   [junit4]   2> 1089607 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[5 (1573604627709952000)]} 0 0
   [junit4]   2> 1089608 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[5 (1573604627711000576)]} 0 0
   [junit4]   2> 1089608 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[6 (1573604627712049152)]} 0 0
   [junit4]   2> 1089609 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[6 (1573604627713097728)]} 0 0
   [junit4]   2> 1089610 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[7 (1573604627714146304)]} 0 0
   [junit4]   2> 1089611 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[7 (1573604627714146304)]} 0 0
   [junit4]   2> 1089612 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[8 (1573604627715194880)]} 0 0
   [junit4]   2> 1089613 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[8 (1573604627716243456)]} 0 0
   [junit4]   2> 1089614 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[9 (1573604627717292032)]} 0 0
   [junit4]   2> 1089614 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[9 (1573604627718340608)]} 0 0
   [junit4]   2> 1089615 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573604627719389184,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1089615 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4e4aa566 
commitCommandVersion:1573604627719389184
   [junit4]   2> 1089636 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@57408462[collection1] main]
   [junit4]   2> 1089637 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1089639 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@57408462[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C9)))}
   [junit4]   2> 1089639 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 23
   [junit4]   2> 1089639 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573604627744555008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1089639 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@e73c244 
commitCommandVersion:1573604627744555008
   [junit4]   2> 1089672 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2d63e1f5[collection1] main]
   [junit4]   2> 1089674 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d63e1f5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C9)))}
   [junit4]   2> 1089675 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1089675 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 36
   [junit4]   2> 1089696 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[10 (1573604627783352320)]} 0 19
   [junit4]   2> 1089707 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[10 (1573604627805372416)]} 0 9
   [junit4]   2> 1089718 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[11 (1573604627815858176)]} 0 10
   [junit4]   2> 1089745 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[11 (1573604627828441088)]} 0 25
   [junit4]   2> 1089746 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[12 (1573604627856752640)]} 0 0
   [junit4]   2> 1089747 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[12 (1573604627857801216)]} 0 0
   [junit4]   2> 1089751 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[13 (1573604627860946944)]} 0 0
   [junit4]   2> 1089752 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[13 (1573604627863044096)]} 0 0
   [junit4]   2> 1089753 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[1001 (1573604627863044096)]} 0 0
   [junit4]   2> 1089755 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[1001 (1573604627865141248)]} 0 0
   [junit4]   2> 1089757 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[14 (1573604627868286976)]} 0 0
   [junit4]   2> 1089759 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[14 (1573604627870384128)]} 0 0
   [junit4]   2> 1089760 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[15 (1573604627871432704)]} 0 0
   [junit4]   2> 1089761 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[15 (1573604627871432704)]} 0 0
   [junit4]   2> 1089761 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[16 (1573604627872481280)]} 0 0
   [junit4]   2> 1089762 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[16 (1573604627873529856)]} 0 0
   [junit4]   2> 1089763 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[17 (1573604627874578432)]} 0 0
   [junit4]   2> 1089768 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[17 (1573604627879821312)]} 0 0
   [junit4]   2> 1089773 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[100 (1573604627881918464)]} 0 2
   [junit4]   2> 1089774 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[100 (1573604627885064192)]} 0 0
   [junit4]   2> 1089774 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[101 (1573604627886112768)]} 0 0
   [junit4]   2> 1089775 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[101 (1573604627887161344)]} 0 0
   [junit4]   2> 1089776 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[102 (1573604627888209920)]} 0 0
   [junit4]   2> 1089776 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[102 (1573604627888209920)]} 0 0
   [junit4]   2> 1089777 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[103 (1573604627889258496)]} 0 0
   [junit4]   2> 1089778 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[103 (1573604627890307072)]} 0 0
   [junit4]   2> 1089779 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[104 (1573604627890307072)]} 0 0
   [junit4]   2> 1089779 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[104 (1573604627891355648)]} 0 0
   [junit4]   2> 1089780 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[105 (1573604627892404224)]} 0 0
   [junit4]   2> 1089781 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[105 (1573604627892404224)]} 0 0
   [junit4]   2> 1089782 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[106 (1573604627893452800)]} 0 0
   [junit4]   2> 1089783 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[106 (1573604627894501376)]} 0 0
   [junit4]   2> 1089783 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[107 (1573604627895549952)]} 0 0
   [junit4]   2> 1089784 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[107 (1573604627896598528)]} 0 0
   [junit4]   2> 1089785 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[108 (1573604627896598528)]} 0 0
   [junit4]   2> 1089785 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[108 (1573604627897647104)]} 0 0
   [junit4]   2> 1089786 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[109 (1573604627898695680)]} 0 0
   [junit4]   2> 1089787 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[109 (1573604627899744256)]} 0 0
   [junit4]   2> 1089787 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[110 (1573604627899744256)]} 0 0
   [junit4]   2> 1089788 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[110 (1573604627900792832)]} 0 0
   [junit4]   2> 1089789 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[111 (1573604627901841408)]} 0 0
   [junit4]   2> 1089790 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[111 (1573604627902889984)]} 0 0
   [junit4]   2> 1089791 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[112 (1573604627902889984)]} 0 0
   [junit4]   2> 1089791 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[112 (1573604627903938560)]} 0 0
   [junit4]   2> 1089792 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[113 (1573604627904987136)]} 0 0
   [junit4]   2> 1089793 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[113 (1573604627904987136)]} 0 0
   [junit4]   2> 1089793 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[114 (1573604627906035712)]} 0 0
   [junit4]   2> 1089794 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[114 (1573604627907084288)]} 0 0
   [junit4]   2> 1089795 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[115 (1573604627907084288)]} 0 0
   [junit4]   2> 1089795 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[115 (1573604627908132864)]} 0 0
   [junit4]   2> 1089796 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[116 (1573604627909181440)]} 0 0
   [junit4]   2> 1089797 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[116 (1573604627909181440)]} 0 0
   [junit4]   2> 1089797 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[117 (1573604627910230016)]} 0 0
   [junit4]   2> 1089798 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[117 (1573604627911278592)]} 0 0
   [junit4]   2> 1089799 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[118 (1573604627912327168)]} 0 0
   [junit4]   2> 1089799 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[118 (1573604627912327168)]} 0 0
   [junit4]   2> 1089800 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[119 (1573604627913375744)]} 0 0
   [junit4]   2> 1089801 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[119 (1573604627914424320)]} 0 0
   [junit4]   2> 1089801 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[120 (1573604627914424320)]} 0 0
   [junit4]   2> 1089802 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[120 (1573604627915472896)]} 0 0
   [junit4]   2> 1089804 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[121 (1573604627917570048)]} 0 0
   [junit4]   2> 1089806 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[121 (1573604627919667200)]} 0 0
   [junit4]   2> 1089807 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[122 (1573604627919667200)]} 0 0
   [junit4]   2> 1089807 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[122 (1573604627920715776)]} 0 0
   [junit4]   2> 1089811 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[123 (1573604627923861504)]} 0 0
   [junit4]   2> 1089811 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[123 (1573604627924910080)]} 0 0
   [junit4]   2> 1089812 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[124 (1573604627925958656)]} 0 0
   [junit4]   2> 1089813 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[124 (1573604627925958656)]} 0 0
   [junit4]   2> 1089813 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[125 (1573604627927007232)]} 0 0
   [junit4]   2> 1089814 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[125 (1573604627928055808)]} 0 0
   [junit4]   2> 1089815 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[126 (1573604627929104384)]} 0 0
   [junit4]   2> 1089816 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[126 (1573604627929104384)]} 0 0
   [junit4]   2> 1089816 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[127 (1573604627930152960)]} 0 0
   [junit4]   2> 1089817 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[127 (1573604627931201536)]} 0 0
   [junit4]   2> 1089818 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[128 (1573604627931201536)]} 0 0
   [junit4]   2> 1089818 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[128 (1573604627932250112)]} 0 0
   [junit4]   2> 1089819 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[129 (1573604627933298688)]} 0 0
   [junit4]   2> 1089820 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[129 (1573604627933298688)]} 0 0
   [junit4]   2> 1089820 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[130 (1573604627934347264)]} 0 0
   [junit4]   2> 1089822 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[130 (1573604627936444416)]} 0 0
   [junit4]   2> 1089823 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[131 (1573604627937492992)]} 0 0
   [junit4]   2> 1089824 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[131 (1573604627937492992)]} 0 0
   [junit4]   2> 1089824 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[132 (1573604627938541568)]} 0 0
   [junit4]   2> 1089825 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[132 (1573604627939590144)]} 0 0
   [junit4]   2> 1089826 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[133 (1573604627940638720)]} 0 0
   [junit4]   2> 1089826 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[133 (1573604627940638720)]} 0 0
   [junit4]   2> 1089827 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[134 (1573604627941687296)]} 0 0
   [junit4]   2> 1089831 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[134 (1573604627944833024)]} 0 0
   [junit4]   2> 1089831 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[135 (1573604627945881600)]} 0 0
   [junit4]   2> 1089832 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[135 (1573604627946930176)]} 0 0
   [junit4]   2> 1089833 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[136 (1573604627946930176)]} 0 0
   [junit4]   2> 1089833 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[136 (1573604627947978752)]} 0 0
   [junit4]   2> 1089834 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[137 (1573604627949027328)]} 0 0
   [junit4]   2> 1089835 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[137 (1573604627950075904)]} 0 0
   [junit4]   2> 1089836 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[138 (1573604627950075904)]} 0 0
   [junit4]   2> 1089836 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[138 (1573604627951124480)]} 0 0
   [junit4]   2> 1089837 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[139 (1573604627952173056)]} 0 0
   [junit4]   2> 1089838 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[139 (1573604627952173056)]} 0 0
   [junit4]   2> 1089838 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[140 (1573604627953221632)]} 0 0
   [junit4]   2> 1089839 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[140 (1573604627954270208)]} 0 0
   [junit4]   2> 1089840 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[141 (1573604627954270208)]} 0 0
   [junit4]   2> 1089840 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[141 (1573604627955318784)]} 0 0
   [junit4]   2> 1089841 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[142 (1573604627956367360)]} 0 0
   [junit4]   2> 1089842 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[142 (1573604627957415936)]} 0 0
   [junit4]   2> 1089843 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[143 (1573604627958464512)]} 0 0
   [junit4]   2> 1089844 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[143 (1573604627958464512)]} 0 0
   [junit4]   2> 1089844 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[144 (1573604627959513088)]} 0 0
   [junit4]   2> 1089846 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[144 (1573604627961610240)]} 0 0
   [junit4]   2> 1089847 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[145 (1573604627962658816)]} 0 0
   [junit4]   2> 1089848 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[145 (1573604627963707392)]} 0 0
   [junit4]   2> 1089848 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[146 (1573604627963707392)]} 0 0
   [junit4]   2> 1089849 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[146 (1573604627964755968)]} 0 0
   [junit4]   2> 1089850 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[147 (1573604627965804544)]} 0 0
   [junit4]   2> 1089850 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[147 (1573604627965804544)]} 0 0
   [junit4]   2> 1089851 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[148 (1573604627966853120)]} 0 0
   [junit4]   2> 1089852 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[148 (1573604627967901696)]} 0 0
   [junit4]   2> 1089853 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[149 (1573604627968950272)]} 0 0
   [junit4]   2> 1089863 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={wt=javabin&version=2}{add=[149 (1573604627978387456)]} 0 0
   [junit4]   2> 1089863 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573604627979436032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1089863 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4e4aa566 
commitCommandVersion:1573604627979436032
   [junit4]   2> 1089901 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2894abb3[collection1] main]
   [junit4]   2> 1089901 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1089904 INFO  
(searcherExecutor-4066-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2894abb3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C9)
 Uninverting(_1(7.1.0):C59)))}
   [junit4]   2> 1089908 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 45
   [junit4]   2> 1089909 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573604628027670528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1089909 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@e73c244 
commitCommandVersion:1573604628027670528
   [junit4]   2> 1089923 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@a0c2e56[collection1] main]
   [junit4]   2> 1089926 INFO  
(searcherExecutor-4077-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a0c2e56[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):C9)
 Uninverting(_1(7.1.0):C59)))}
   [junit4]   2> 1089926 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1089926 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/ww_ki/i path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 16
   [junit4]   2> 1089927 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1089927 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1089931 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1089931 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1089932 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1089979 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706317956&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1089996 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706317956&ids=132,12,102,146,4,15,16,149,128,130&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1089998 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=60
   [junit4]   2> 1089999 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090188 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318019&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090200 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318019&ids=110,143,13,125,126,6,17,7,139,120&isShard=true&shard.url=[ff01::114]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090225 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::114]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=224
   [junit4]   2> 1090227 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090231 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318249&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090234 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318249&ids=143,11,13,112,115,148,126,139,1001,107&isShard=true&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090235 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1090237 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090239 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318257&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090240 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318257&ids=132,101,123,104,137,6,17,7,8,119&isShard=true&shard.url=[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090300 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=61
   [junit4]   2> 1090301 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090303 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318321&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090304 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318321&ids=121,113,102,16,115,148,6,105,129,120&isShard=true&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090308 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1090309 INFO  (qtp628874489-9616) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090315 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::114]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318331&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090317 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318331&ids=144,12,3,14,147,114,128,118,130,141&isShard=true&shard.url=[ff01::114]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090332 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::114]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=20
   [junit4]   2> 1090334 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090337 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i 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:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318355&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090340 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318355&ids=132,101,124,102,4,148,149,127,118,120&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=2
   [junit4]   2> 1090350 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=13
   [junit4]   2> 1090351 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090353 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i 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:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318371&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090354 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318371&ids=111,122,112,13,15,137,126,17,139,9&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090370 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=18
   [junit4]   2> 1090371 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090373 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318392&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090374 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318392&ids=110,12,144,15,4,127,6,7,140,10&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090375 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1090376 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090378 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318396&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090379 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318396&ids=2,134,14,136,137,148,126,119,130,142&isShard=true&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090380 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1090381 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090386 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318401&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090387 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318401&ids=3,136,148,16,138,116,139,1001,119,120&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090396 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=14
   [junit4]   2> 1090397 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090401 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i 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:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318418&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090402 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318418&ids=132,100,144,102,113,115,17,7,9,130&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090403 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1090404 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090405 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318424&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090409 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318424&ids=123,102,113,124,15,126,17,116,138,130&isShard=true&shard.url=[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090410 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1090411 INFO  (qtp628874489-9617) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090413 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318431&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090414 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318431&ids=143,112,3,135,136,5,104,127,7,141&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090415 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i|[ff01::083]:33332/ww_ki/i&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1090416 INFO  (qtp628874489-9614) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090427 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i 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:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318445&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090428 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318445&ids=132,111,122,103,148,127,6,117,7,142&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090429 INFO  (qtp1032137764-9632) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1090431 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090435 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::083]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318453&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090436 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318453&ids=121,15,147,104,149,128,119,108,140,120&isShard=true&shard.url=[ff01::083]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090440 INFO  (qtp1032137764-9631) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::083]:33332/ww_ki/i|[ff01::213]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::114]:33332/ww_ki/i&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 1090441 INFO  (qtp628874489-9615) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090446 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i 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/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318464&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090447 INFO  (qtp1032137764-9628) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318464&ids=133,113,15,4,6,7,139,9,131,10&isShard=true&shard.url=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090448 INFO  (qtp1032137764-9633) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=[ff01::213]:33332/ww_ki/i|[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::083]:33332/ww_ki/i&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1090449 INFO  (qtp628874489-9610) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090451 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i 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:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i&rows=10&version=2&q=*:*&NOW=1500706318469&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090452 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318469&ids=110,111,101,124,146,103,126,5,104,1001&isShard=true&shard.url=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090453 INFO  (qtp1032137764-9634) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&shards=http://127.0.0.1:48210/ww_ki/i/collection1|[ff01::213]:33332/ww_ki/i&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1090453 INFO  (qtp628874489-9613) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&distrib=false&sort=a_i_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1090455 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+desc&shard.url=[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&rows=10&version=2&q=*:*&NOW=1500706318474&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1090456 INFO  (qtp1032137764-9630) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/ww_ki/i path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500706318474&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/ww_ki/i|http://127.0.0.1:48210/ww_ki/i/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1090457 INFO  (qtp1032137764-9627) [    x:collection1] 
o.a.s.c.S.Request [collection1]  weba

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

junit4]   2> 1128792 INFO  (coreCloseExecutor-4121-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1362478893
   [junit4]   2> 1128795 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6677f912{/ww_ki/i,null,UNAVAILABLE}
   [junit4]   2> 1128798 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@6612ab1c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1128798 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=2081669620
   [junit4]   2> 1128798 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1128798 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1128799 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1128799 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1128799 INFO  (coreCloseExecutor-4123-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1256fddf
   [junit4]   2> 1128800 INFO  (coreCloseExecutor-4123-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=307690975
   [junit4]   2> 1128802 INFO  
(TEST-TestDistributedSearch.test-seed#[9BA433AEAB40BDEA]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3d878cb4{/ww_ki/i,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=9BA433AEAB40BDEA -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=fr-LU -Dtests.timezone=America/Rio_Branco -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 41.7s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
   [junit4]    >        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]    >        at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]    >        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
   [junit4]    >        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]    >        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > ,time=2}
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([9BA433AEAB40BDEA:13F00C7405BCD012]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1191)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_9BA433AEAB40BDEA-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{severity=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
foo_sev_enum=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 SubjectTerms_mfacet=PostingsFormat(name=Direct), 
multiDefault=PostingsFormat(name=LuceneFixedGap), rnd_b=FST50, 
a_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 oddField_s=FST50, lowerfilt1and2=PostingsFormat(name=LuceneFixedGap), 
a_t=PostingsFormat(name=LuceneFixedGap), 
foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 lowerfilt=PostingsFormat(name=LuceneFixedGap), id=FST50, 
text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
 docValues:{other_tl1=DocValuesFormat(name=Lucene70), 
range_facet_l_dv=DocValuesFormat(name=Memory), 
foo_sev_enum=DocValuesFormat(name=Asserting), 
n_l1=DocValuesFormat(name=Direct), a_n_tdt=DocValuesFormat(name=Asserting), 
intDefault=DocValuesFormat(name=Lucene70), 
n_dt1=DocValuesFormat(name=Lucene70), n_td1=DocValuesFormat(name=Lucene70), 
b_n_tdt=DocValuesFormat(name=Lucene70), n_d1=DocValuesFormat(name=Direct), 
range_facet_l=DocValuesFormat(name=Direct), 
n_f1=DocValuesFormat(name=Lucene70), n_ti1=DocValuesFormat(name=Memory), 
n_tl1=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Lucene70), 
n_tf1=DocValuesFormat(name=Direct), n_tdt1=DocValuesFormat(name=Lucene70), 
id_i1=DocValuesFormat(name=Lucene70), foo_d=DocValuesFormat(name=Memory), 
range_facet_i_dv=DocValuesFormat(name=Direct), 
foo_f=DocValuesFormat(name=Asserting), 
intDvoDefault=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Direct), a_i_p=DocValuesFormat(name=Lucene70)}, 
maxPointsInLeafNode=1550, maxMBSortInHeap=5.441018291229771, 
sim=RandomSimilarity(queryNorm=false): {}, locale=fr-LU, 
timezone=America/Rio_Branco
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=249300584,total=532676608
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrFieldCacheBean, 
DateRangeFieldTest, NodeMutatorTest, TestFunctionQuery, 
TestCloudJSONFacetJoinDomain, TestPayloadScoreQParserPlugin, TestSmileRequest, 
TestQuerySenderNoQuery, SuggestComponentTest, TestHalfAndHalfDocValues, 
CloudMLTQParserTest, TestCrossCoreJoin, TestDFISimilarityFactory, 
SolrIndexSplitterTest, CdcrBootstrapTest, ConjunctionSolrSpellCheckerTest, 
SynonymTokenizerTest, PeerSyncTest, MoveReplicaHDFSUlogDirTest, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestDynamicFieldResource, 
RequestLoggingTest, TestMacros, FieldMutatingUpdateProcessorTest, 
SolrPluginUtilsTest, TestClassNameShortening, TestLuceneMatchVersion, 
LeaderElectionIntegrationTest, TestSubQueryTransformerCrossCore, 
DistribDocExpirationUpdateProcessorTest, BasicZkTest, FacetPivotSmallTest, 
TestSolrDeletionPolicy1, SolrCLIZkUtilsTest, TestRandomDVFaceting, 
TestSolr4Spatial, HttpPartitionTest, ManagedSchemaRoundRobinCloudTest, 
TestOrdValues, TestShardHandlerFactory, TestFieldSortValues, TestPullReplica, 
TestBlendedInfixSuggestions, TestFaceting, InfoHandlerTest, 
DistributedFacetExistsSmallTest, HdfsBasicDistributedZkTest, 
SpatialRPTFieldTypeTest, SolrJmxReporterTest, ConnectionReuseTest, TestDocSet, 
LeaderFailureAfterFreshStartTest, TestSolrQueryResponse, SortSpecParsingTest, 
TestScoreJoinQPNoScore, HdfsCollectionsAPIDistributedZkTest, TestInitParams, 
TestFieldCacheSort, HighlighterConfigTest, JavabinLoaderTest, 
TestOmitPositions, RuleEngineTest, TestCoreAdminApis, TestComponentsName, 
OpenExchangeRatesOrgProviderTest, TestDynamicLoading, SolrRequestParserTest, 
HdfsDirectoryFactoryTest, TestSolrCloudWithDelegationTokens, DeleteShardTest, 
TestSolrCloudWithHadoopAuthPlugin, TestDistribDocBasedVersion, 
CreateCollectionCleanupTest, TestMergePolicyConfig, 
TestFieldTypeCollectionResource, ConcurrentDeleteAndCreateCollectionTest, 
QueryElevationComponentTest, UUIDUpdateProcessorFallbackTest, 
SuggesterWFSTTest, HdfsThreadLeakTest, CoreAdminCreateDiscoverTest, 
TestExtendedDismaxParser, TestBinaryResponseWriter, SecurityConfHandlerTest, 
TestRandomFaceting, TestAuthorizationFramework, 
ParsingFieldUpdateProcessorsTest, TestDocumentBuilder, TestRemoteStreaming, 
SpellCheckCollatorTest, BasicDistributedZkTest, 
TestLeaderInitiatedRecoveryThread, TestApiFramework, SolrShardReporterTest, 
BufferStoreTest, BooleanFieldTest, AssignTest, TestDocTermOrds, 
AnalyticsMergeStrategyTest, DistributedQueryElevationComponentTest, TestJoin, 
PluginInfoTest, RequestHandlersTest, TestIBSimilarityFactory, 
TestNumericRangeQuery32, XmlUpdateRequestHandlerTest, TestSystemCollAutoCreate, 
WordBreakSolrSpellCheckerTest, DistributedFacetPivotSmallAdvancedTest, 
OverseerCollectionConfigSetProcessorTest, FieldAnalysisRequestHandlerTest, 
RAMDirectoryFactoryTest, TestRangeQuery, TestFastOutputStream, 
TestLMDirichletSimilarityFactory, UnloadDistributedZkTest, SyncSliceTest, 
ZkSolrClientTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch]
   [junit4] Completed [353/727 (1!)] on J0 in 41.71s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 44473 lines...]

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