Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2352/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseParallelGC

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

Error Message:
Expected to find shardAddress in the up shard info

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info
        at 
__randomizedtesting.SeedInfo.seed([A8585CADDE958709:200C63777069EAF1]: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:1162)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
        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:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11519 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/init-core-data-001
   [junit4]   2> 716048 INFO  
(SUITE-TestDistributedSearch-seed#[A8585CADDE958709]-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> 716048 INFO  
(SUITE-TestDistributedSearch-seed#[A8585CADDE958709]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 716167 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/control/cores/collection1
   [junit4]   2> 716184 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 716186 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7e9ac591{/,null,AVAILABLE}
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5954ed1b{HTTP/1.1,[http/1.1]}{127.0.0.1:33248}
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] o.e.j.s.Server 
Started @718134ms
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=33248, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/control/cores}
   [junit4]   2> 716188 ERROR 
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.4.0
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 716188 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-12-06T08:17:28.520Z
   [junit4]   2> 716189 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/control/solr.xml
   [junit4]   2> 716193 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 716210 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/control/cores
   [junit4]   2> 716210 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 716210 INFO  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 716234 WARN  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 716235 INFO  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 716252 INFO  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 716311 WARN  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 716313 INFO  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 716317 INFO  (coreLoadExecutor-2732-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 716317 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 716317 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@743371eb
   [junit4]   2> 716318 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4371083800193559]
   [junit4]   2> 716326 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 716326 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 716326 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 716326 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 716327 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=38, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4610080650708005]
   [junit4]   2> 716327 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@783bb1f7[collection1] main]
   [junit4]   2> 716327 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 716366 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 716524 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 716524 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 716524 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 716525 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 716525 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 716525 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 716525 INFO  (coreLoadExecutor-2732-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1552953899857477632
   [junit4]   2> 716525 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 716525 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 716525 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 716526 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 716526 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@783bb1f7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 716775 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 716776 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 716777 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@43112e72{/,null,AVAILABLE}
   [junit4]   2> 716777 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6815cd39{HTTP/1.1,[http/1.1]}{127.0.0.1:44696}
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] o.e.j.s.Server 
Started @718724ms
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=44696, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/shard0/cores}
   [junit4]   2> 716778 ERROR 
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.4.0
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-12-06T08:17:29.110Z
   [junit4]   2> 716778 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 716783 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 716784 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/shard0/cores
   [junit4]   2> 716784 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 716786 INFO  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 716806 WARN  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 716807 INFO  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 716823 INFO  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 716893 WARN  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 716894 INFO  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 716899 INFO  (coreLoadExecutor-2743-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 716899 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 716899 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@743371eb
   [junit4]   2> 716899 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4371083800193559]
   [junit4]   2> 716909 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 716909 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 716910 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 716910 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 716910 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=38, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4610080650708005]
   [junit4]   2> 716910 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2b50bdca[collection1] main]
   [junit4]   2> 716911 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 716930 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 717043 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 717043 INFO  (coreLoadExecutor-2743-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1552953900400640000
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 717043 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 717044 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 717044 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 717044 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2b50bdca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 717285 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 717288 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1552953900656492544)} 0 1
   [junit4]   2> 717289 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1552953900657541120)} 0 1
   [junit4]   2> 717292 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1552953900660686848)]} 0 1
   [junit4]   2> 717295 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1552953900663832576)]} 0 1
   [junit4]   2> 717297 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1552953900665929728)]} 0 0
   [junit4]   2> 717298 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1552953900668026880)]} 0 0
   [junit4]   2> 717300 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1552953900670124032)]} 0 0
   [junit4]   2> 717302 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1552953900672221184)]} 0 0
   [junit4]   2> 717304 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1552953900673269760)]} 0 0
   [junit4]   2> 717305 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1552953900675366912)]} 0 0
   [junit4]   2> 717307 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1552953900677464064)]} 0 0
   [junit4]   2> 717309 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1552953900678512640)]} 0 0
   [junit4]   2> 717310 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1552953900680609792)]} 0 0
   [junit4]   2> 717312 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1552953900682706944)]} 0 0
   [junit4]   2> 717314 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1552953900683755520)]} 0 0
   [junit4]   2> 717315 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1552953900685852672)]} 0 0
   [junit4]   2> 717317 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1552953900687949824)]} 0 0
   [junit4]   2> 717319 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1552953900688998400)]} 0 0
   [junit4]   2> 717320 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1552953900691095552)]} 0 0
   [junit4]   2> 717322 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1552953900693192704)]} 0 0
   [junit4]   2> 717324 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 717324 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@462452f2
   [junit4]   2> 717330 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@46d0ec27[collection1] main]
   [junit4]   2> 717330 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 717331 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@46d0ec27[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):c9)))}
   [junit4]   2> 717331 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 717332 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 717332 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@754b435c
   [junit4]   2> 717336 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5ec48158[collection1] main]
   [junit4]   2> 717336 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 717338 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ec48158[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):c9)))}
   [junit4]   2> 717338 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 717341 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1552953900711018496)]} 0 1
   [junit4]   2> 717343 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1552953900714164224)]} 0 0
   [junit4]   2> 717345 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1552953900717309952)]} 0 0
   [junit4]   2> 717347 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1552953900719407104)]} 0 0
   [junit4]   2> 717349 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1552953900720455680)]} 0 0
   [junit4]   2> 717351 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1552953900723601408)]} 0 0
   [junit4]   2> 717353 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1552953900724649984)]} 0 0
   [junit4]   2> 717354 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1552953900726747136)]} 0 0
   [junit4]   2> 717356 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1552953900728844288)]} 0 0
   [junit4]   2> 717358 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1552953900730941440)]} 0 0
   [junit4]   2> 717360 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1552953900733038592)]} 0 0
   [junit4]   2> 717361 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1552953900734087168)]} 0 0
   [junit4]   2> 717363 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1552953900736184320)]} 0 0
   [junit4]   2> 717365 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1552953900737232896)]} 0 0
   [junit4]   2> 717366 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1552953900739330048)]} 0 0
   [junit4]   2> 717368 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1552953900740378624)]} 0 0
   [junit4]   2> 717370 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1552953900742475776)]} 0 0
   [junit4]   2> 717371 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1552953900744572928)]} 0 0
   [junit4]   2> 717373 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1552953900746670080)]} 0 0
   [junit4]   2> 717375 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1552953900748767232)]} 0 0
   [junit4]   2> 717377 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1552953900750864384)]} 0 0
   [junit4]   2> 717378 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1552953900751912960)]} 0 0
   [junit4]   2> 717380 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1552953900754010112)]} 0 0
   [junit4]   2> 717382 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1552953900755058688)]} 0 0
   [junit4]   2> 717383 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1552953900757155840)]} 0 0
   [junit4]   2> 717386 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1552953900759252992)]} 0 0
   [junit4]   2> 717387 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1552953900761350144)]} 0 0
   [junit4]   2> 717389 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1552953900763447296)]} 0 0
   [junit4]   2> 717391 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1552953900764495872)]} 0 0
   [junit4]   2> 717392 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1552953900766593024)]} 0 0
   [junit4]   2> 717394 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1552953900768690176)]} 0 0
   [junit4]   2> 717395 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1552953900769738752)]} 0 0
   [junit4]   2> 717397 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1552953900771835904)]} 0 0
   [junit4]   2> 717399 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1552953900773933056)]} 0 0
   [junit4]   2> 717400 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1552953900774981632)]} 0 0
   [junit4]   2> 717402 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1552953900777078784)]} 0 0
   [junit4]   2> 717404 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1552953900778127360)]} 0 0
   [junit4]   2> 717405 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1552953900780224512)]} 0 0
   [junit4]   2> 717407 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1552953900782321664)]} 0 0
   [junit4]   2> 717409 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1552953900783370240)]} 0 0
   [junit4]   2> 717410 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1552953900785467392)]} 0 0
   [junit4]   2> 717412 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1552953900787564544)]} 0 0
   [junit4]   2> 717413 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1552953900788613120)]} 0 0
   [junit4]   2> 717415 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1552953900790710272)]} 0 0
   [junit4]   2> 717417 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1552953900792807424)]} 0 0
   [junit4]   2> 717418 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1552953900793856000)]} 0 0
   [junit4]   2> 717420 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1552953900795953152)]} 0 0
   [junit4]   2> 717421 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1552953900797001728)]} 0 0
   [junit4]   2> 717423 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1552953900799098880)]} 0 0
   [junit4]   2> 717425 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1552953900800147456)]} 0 0
   [junit4]   2> 717426 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1552953900802244608)]} 0 0
   [junit4]   2> 717428 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1552953900804341760)]} 0 0
   [junit4]   2> 717430 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1552953900806438912)]} 0 0
   [junit4]   2> 717432 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1552953900807487488)]} 0 0
   [junit4]   2> 717433 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1552953900809584640)]} 0 0
   [junit4]   2> 717435 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1552953900811681792)]} 0 0
   [junit4]   2> 717437 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1552953900813778944)]} 0 0
   [junit4]   2> 717438 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1552953900814827520)]} 0 0
   [junit4]   2> 717440 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1552953900816924672)]} 0 0
   [junit4]   2> 717441 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1552953900817973248)]} 0 0
   [junit4]   2> 717443 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1552953900820070400)]} 0 0
   [junit4]   2> 717445 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1552953900822167552)]} 0 0
   [junit4]   2> 717447 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1552953900824264704)]} 0 0
   [junit4]   2> 717449 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1552953900826361856)]} 0 0
   [junit4]   2> 717450 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1552953900827410432)]} 0 0
   [junit4]   2> 717452 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1552953900829507584)]} 0 0
   [junit4]   2> 717454 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1552953900830556160)]} 0 0
   [junit4]   2> 717455 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1552953900832653312)]} 0 0
   [junit4]   2> 717457 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1552953900834750464)]} 0 0
   [junit4]   2> 717459 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1552953900836847616)]} 0 0
   [junit4]   2> 717460 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1552953900837896192)]} 0 0
   [junit4]   2> 717462 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1552953900839993344)]} 0 0
   [junit4]   2> 717464 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1552953900842090496)]} 0 0
   [junit4]   2> 717465 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1552953900843139072)]} 0 0
   [junit4]   2> 717467 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1552953900845236224)]} 0 0
   [junit4]   2> 717469 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1552953900847333376)]} 0 0
   [junit4]   2> 717471 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1552953900848381952)]} 0 0
   [junit4]   2> 717472 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1552953900850479104)]} 0 0
   [junit4]   2> 717474 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1552953900852576256)]} 0 0
   [junit4]   2> 717476 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1552953900853624832)]} 0 0
   [junit4]   2> 717477 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1552953900855721984)]} 0 0
   [junit4]   2> 717479 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1552953900857819136)]} 0 0
   [junit4]   2> 717480 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1552953900858867712)]} 0 0
   [junit4]   2> 717482 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1552953900860964864)]} 0 0
   [junit4]   2> 717484 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1552953900863062016)]} 0 0
   [junit4]   2> 717486 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1552953900864110592)]} 0 0
   [junit4]   2> 717487 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1552953900866207744)]} 0 0
   [junit4]   2> 717489 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1552953900868304896)]} 0 0
   [junit4]   2> 717491 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1552953900870402048)]} 0 0
   [junit4]   2> 717493 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1552953900871450624)]} 0 0
   [junit4]   2> 717494 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1552953900873547776)]} 0 0
   [junit4]   2> 717496 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1552953900875644928)]} 0 0
   [junit4]   2> 717498 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1552953900877742080)]} 0 0
   [junit4]   2> 717500 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1552953900878790656)]} 0 0
   [junit4]   2> 717501 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1552953900880887808)]} 0 0
   [junit4]   2> 717503 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1552953900882984960)]} 0 0
   [junit4]   2> 717504 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1552953900884033536)]} 0 0
   [junit4]   2> 717506 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1552953900886130688)]} 0 0
   [junit4]   2> 717508 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1552953900888227840)]} 0 0
   [junit4]   2> 717510 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1552953900889276416)]} 0 0
   [junit4]   2> 717511 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1552953900891373568)]} 0 0
   [junit4]   2> 717513 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1552953900893470720)]} 0 0
   [junit4]   2> 717515 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1552953900894519296)]} 0 0
   [junit4]   2> 717516 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1552953900896616448)]} 0 0
   [junit4]   2> 717518 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1552953900898713600)]} 0 0
   [junit4]   2> 717520 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1552953900900810752)]} 0 0
   [junit4]   2> 717522 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1552953900902907904)]} 0 0
   [junit4]   2> 717523 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1552953900903956480)]} 0 0
   [junit4]   2> 717525 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1552953900906053632)]} 0 0
   [junit4]   2> 717526 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1552953900907102208)]} 0 0
   [junit4]   2> 717528 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1552953900909199360)]} 0 0
   [junit4]   2> 717529 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1552953900910247936)]} 0 0
   [junit4]   2> 717531 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1552953900912345088)]} 0 0
   [junit4]   2> 717533 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1552953900913393664)]} 0 0
   [junit4]   2> 717534 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1552953900915490816)]} 0 0
   [junit4]   2> 717536 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1552953900917587968)]} 0 0
   [junit4]   2> 717537 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1552953900918636544)]} 0 0
   [junit4]   2> 717539 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1552953900920733696)]} 0 0
   [junit4]   2> 717540 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 717540 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@462452f2
   [junit4]   2> 717547 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3e25db12[collection1] main]
   [junit4]   2> 717548 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 717548 INFO  
(searcherExecutor-2733-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3e25db12[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):c9)
 Uninverting(_1(6.4.0):c59)))}
   [junit4]   2> 717548 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 717550 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 717550 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@754b435c
   [junit4]   2> 717557 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@59a74133[collection1] main]
   [junit4]   2> 717557 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 717557 INFO  
(searcherExecutor-2744-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@59a74133[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):c9)
 Uninverting(_1(6.4.0):c59)))}
   [junit4]   2> 717557 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 717560 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 717560 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 717561 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 717561 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 717563 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717570 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012249898&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717573 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249898&ids=132,122,100,101,14,4,114,104,119,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717587 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44696//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=20
   [junit4]   2> 717590 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717595 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012249924&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717598 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249924&ids=11,110,13,146,147,116,105,129,141,120&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717600 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 717604 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717608 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:44696//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1481012249938&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717611 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249938&ids=110,111,144,145,147,127,117,8,109,120&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717612 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::083]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 717615 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717620 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012249949&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717623 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249949&ids=132,134,15,5,115,104,149,129,140,142&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717624 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 717627 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717632 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012249962&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717634 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249962&ids=112,145,136,4,147,137,148,117,8,142&isShard=true&shard.url=http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717635 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 717637 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717641 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1481012249972&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717644 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249972&ids=100,123,135,114,126,6,107,129,10,120&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717646 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 717648 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717653 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012249983&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717655 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249983&ids=143,125,126,1001,117,128,107,8,109,141&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717655 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717657 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717661 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012249991&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717663 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012249991&ids=12,134,146,113,115,137,17,106,9,108&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717664 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:44696//collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717666 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717669 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250000&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717671 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250000&ids=121,11,144,102,5,6,138,106,107,109&isShard=true&shard.url=http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717672 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717673 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717677 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250007&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717679 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250007&ids=12,4,147,125,15,137,117,7,129,141&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717679 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:44696//collection1&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717681 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717685 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250015&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717687 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250015&ids=134,124,135,15,5,126,104,117,9,120&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717688 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717690 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717693 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250024&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717695 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250024&ids=133,123,112,136,6,106,8,140,10,142&isShard=true&shard.url=http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717696 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717699 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717702 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250033&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717704 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250033&ids=122,13,2,3,135,137,104,105,139,141&isShard=true&shard.url=http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717705 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717707 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717711 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250041&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717713 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250041&ids=12,100,113,103,136,148,6,1001,8,9&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717713 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:44696//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 717715 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717719 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1481012250049&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717721 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250049&ids=145,113,14,147,16,138,139,129,141,131&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717722 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717724 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717727 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250058&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717729 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250058&ids=132,100,101,3,5,149,105,6,1001,118&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717730 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 717732 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717736 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250066&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717738 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250066&ids=122,146,15,137,104,116,17,139,9,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717739 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:44696//collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717741 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717745 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:44696//collection1&rows=10&version=2&q=*:*&NOW=1481012250075&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717747 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250075&ids=110,132,123,2,14,147,125,105,117,142&isShard=true&shard.url=http://127.0.0.1:44696//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717747 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717750 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717753 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:44696//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1481012250084&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717755 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250084&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717756 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::083]:33332/&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717759 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 717763 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1481012250093&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717765 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250093&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717766 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717768 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717771 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1481012250102&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717773 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250102&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717774 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&sort=a_i1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717776 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 717779 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:44696//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1481012250110&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717781 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1481012250110&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717782 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::114]:33332/&fl=*,score&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 717784 INFO  (qtp2007013498-4970) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 717788 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:44696//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1481012250118&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717789 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1481012250118&ids=12,100,113,103,136,148,6,1001,8,9&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717790 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44696//collection1|[ff01::114]:33332/&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 717792 INFO  (qtp2007013498-4977) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717796 INFO  (qtp487358168-4992) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1481012250126&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 717798 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1481012250126&ids=122,13,2,3,135,137,104,105,139,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717798 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:44696//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 717800 INFO  (qtp2007013498-4976) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&wt=javabin&version=2} hits=68 status=0 
QTime=0
   [junit4]   2> 717804 INFO  (qtp487358168-4993) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1481012250134&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&rows=10&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 717805 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&shards.purpose=64&NOW=1481012250134&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 717806 INFO  (qtp487358168-4987) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&shards=http://127.0.0.1:44696//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 717808 INFO  (qtp2007013498-4973) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&fl=*,score&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 717811 INFO  (qtp487358168-4988) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1481012250142&start=0&fsv=true&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44696//collection1&rows=10&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 717813 INFO  (qtp487358168-4994) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&fl=*,score&shards.purpose=64&NOW=1481012250142&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&sha

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

:collection1] o.a.s.h.c.SearchHandler Query: 
distrib=false&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
1722359395684716 (System.nanoTime(): 1722359403196153), 
TermsEnum=org.apache.lucene.codecs.blocktree.SegmentTermsEnum@709f9ba3
   [junit4]   2> 777728 INFO  (qtp402700523-5019) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=11
   [junit4]   2> 777736 WARN  (qtp294817485-5035) [    x:collection1] 
o.a.s.h.c.SearchHandler Query: 
distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i1+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:45883//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1481012310062&isShard=true&timeAllowed=1&wt=javabin&group=true;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
1722359411197178 (System.nanoTime(): 1722359412020992), 
TermsEnum=org.apache.lucene.codecs.blocktree.SegmentTermsEnum@1dd034a5
   [junit4]   2> 777737 INFO  (qtp294817485-5035) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i1+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:45883//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1481012310062&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=2
   [junit4]   2> 777748 INFO  (qtp807873142-5052) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=http://127.0.0.1:45883//collection1,[ff01::114]:33332/|http://127.0.0.1:36278//collection1|[ff01::213]:33332/&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=18
   [junit4]   2> 777750 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 777751 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@7545d66d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 777751 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1352012719
   [junit4]   2> 777751 INFO  (coreCloseExecutor-2786-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@181bdc99
   [junit4]   2> 777761 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4a1bfd26{/,null,UNAVAILABLE}
   [junit4]   2> 777762 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@78b0800{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 777762 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=993415514
   [junit4]   2> 777763 INFO  (coreCloseExecutor-2788-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@16cd5669
   [junit4]   2> 777792 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3bea9465{/,null,UNAVAILABLE}
   [junit4]   2> 777794 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@4c3dc500{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 777794 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1435574245
   [junit4]   2> 777794 INFO  (coreCloseExecutor-2790-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@459f7be
   [junit4]   2> 777806 INFO  
(TEST-TestDistributedSearch.test-seed#[A8585CADDE958709]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@282c2a07{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=A8585CADDE958709 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=nl-NL -Dtests.timezone=Pacific/Niue -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 61.8s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A8585CADDE958709:200C63777069EAF1]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 777809 INFO  
(SUITE-TestDistributedSearch-seed#[A8585CADDE958709]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_A8585CADDE958709-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{other_tl1=Lucene50(blocksize=128), 
range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), 
SubjectTerms_mfacet=Lucene50(blocksize=128), n_l1=Lucene50(blocksize=128), 
a_n_tdt=PostingsFormat(name=LuceneFixedGap), 
multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), 
intDefault=Lucene50(blocksize=128), 
n_td1=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_d1=Lucene50(blocksize=128), 
n_f1=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_l=Lucene50(blocksize=128), 
n_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), 
oddField_s=PostingsFormat(name=Memory doPackFST= false), 
n_tf1=Lucene50(blocksize=128), 
lowerfilt1and2=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_t=Lucene50(blocksize=128), 
lowerfilt=PostingsFormat(name=LuceneVarGapFixedInterval), 
id=PostingsFormat(name=LuceneFixedGap), text=PostingsFormat(name=Memory 
doPackFST= false), timestamp=Lucene50(blocksize=128), 
severity=PostingsFormat(name=Memory doPackFST= false), 
foo_sev_enum=PostingsFormat(name=LuceneFixedGap), 
n_dt1=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_i1=Lucene50(blocksize=128), b_n_tdt=Lucene50(blocksize=128), 
n_ti1=PostingsFormat(name=LuceneFixedGap), 
rnd_b=PostingsFormat(name=LuceneFixedGap), _version_=Lucene50(blocksize=128), 
foo_b=PostingsFormat(name=Memory doPackFST= false), 
n_tdt1=Lucene50(blocksize=128), foo_d=PostingsFormat(name=LuceneFixedGap), 
range_facet_i_dv=Lucene50(blocksize=128), foo_f=PostingsFormat(name=Memory 
doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), 
foo_sev_enum=DocValuesFormat(name=Lucene54), 
range_facet_i_dv=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1948, 
maxMBSortInHeap=5.78279823190105, 
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=nl-NL, 
timezone=Pacific/Niue
   [junit4]   2> NOTE: Linux 4.4.0-47-generic amd64/Oracle Corporation 
1.8.0_102 (64-bit)/cpus=12,threads=1,free=267326016,total=503316480
   [junit4]   2> NOTE: All tests run in this JVM: [HttpPartitionTest, 
TestFoldingMultitermQuery, TestSolrDeletionPolicy2, 
DistributedFacetPivotSmallAdvancedTest, TestSchemaVersionResource, 
TestBulkSchemaConcurrent, CloudMLTQParserTest, TestComponentsName, 
TestCustomStream, TestSolrQueryParserResource, TestHdfsUpdateLog, 
SignatureUpdateProcessorFactoryTest, TestReloadAndDeleteDocs, BasicZkTest, 
ExitableDirectoryReaderTest, PeerSyncReplicationTest, PeerSyncTest, 
UUIDUpdateProcessorFallbackTest, TestPushWriter, TestLazyCores, 
LeaderElectionIntegrationTest, TestConfigReload, TestSchemaNameResource, 
TestFaceting, TestSubQueryTransformerCrossCore, MBeansHandlerTest, 
TestOmitPositions, RankQueryTest, TestSha256AuthenticationProvider, 
TestScoreJoinQPNoScore, StandardRequestHandlerTest, 
HdfsRestartWhileUpdatingTest, FacetPivotSmallTest, TestRealTimeGet, 
TestAtomicUpdateErrorCases, TestCloudRecovery, 
DeleteLastCustomShardedReplicaTest, TestCrossCoreJoin, TestJoin, 
DistributedQueryComponentCustomSortTest, TestCodecSupport, SyncSliceTest, 
TestBackupRepositoryFactory, TestTrackingShardHandlerFactory, 
FastVectorHighlighterTest, TestDistributedStatsComponentCardinality, 
AtomicUpdatesTest, TestSmileRequest, DefaultValueUpdateProcessorTest, 
EnumFieldTest, HLLSerializationTest, SolrRequestParserTest, TestCryptoKeys, 
TestReqParamsAPI, TestConfigSetsAPI, TestConfigSetProperties, InfoHandlerTest, 
QueryEqualityTest, CachingDirectoryFactoryTest, AnalyticsQueryTest, 
TestFileDictionaryLookup, StatsReloadRaceTest, TestMaxScoreQueryParser, 
HdfsChaosMonkeyNothingIsSafeTest, TestCSVLoader, 
AsyncCallRequestStatusResponseTest, TestFieldCacheWithThreads, 
TestUseDocValuesAsStored, TestBadConfig, SolrCoreTest, TestDistributedSearch]
   [junit4] Completed [255/660 (1!)] on J0 in 61.79s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 62081 lines...]

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

Reply via email to