[JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+159) - Build # 3051 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3051/
Java: 32bit/jdk-9-ea+159 -server -XX:+UseG1GC

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([3CB1719F7C30D0:88688EAB31805D28]: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:1176)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1117)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:977)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:547)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 11137 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_3CB1719F7C30D0-001/init-core-data-001
   [junit4]   2> 69257 WARN  
(SUITE-TestDistributedSearch-seed#[3CB1719F7C30D0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
   [junit4]   2> 69257 INFO  
(SUITE-TestDistributedSearch-seed#[3CB1719F7C30D0]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 69259 INFO  
(SUITE-TestDistributedSearch-seed#[3CB1719F7C30D0]-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> 69260 INFO  
(SUITE-TestDistributedSearch-seed#[3CB1719F7C30D0]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 69997 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/control/cores/collection1
   [junit4]   2> 70003 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 70004 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@50d6e9{/,null,AVAILABLE}
   [junit4]   2> 70006 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1f30fda{HTTP/1.1,[http/1.1]}{127.0.0.1:৩৮৮৬৬}
   [junit4]   2> 70006 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] o.e.j.s.Server 
Started @৭১৯২৭ms
   [junit4]   2> 70006 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=38866, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3CB1719F7C30D0-001/tempDir-001/control/cores}
   [junit4]   2> 70007 ERROR 
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 70007 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 70007 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 70007 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 70007 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-13T19:14:20.579346Z
   [junit4]   2> 70008 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/control/solr.xml
   [junit4]   2> 70016 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 70116 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/control/cores
   [junit4]   2> 70116 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 70118 INFO  (coreLoadExecutor-313-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> 70189 WARN  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 70190 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 70214 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 70284 WARN  (coreLoadExecutor-313-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> 70289 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 70312 INFO  (coreLoadExecutor-313-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> 70313 INFO  (coreLoadExecutor-313-thread-1) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 70313 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@11af356
   [junit4]   2> 70314 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4441498295559708]
   [junit4]   2> 70458 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 70458 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 70460 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 70460 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 70461 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 70461 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1db38d3[collection1] main]
   [junit4]   2> 70462 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 70516 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 70725 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 70726 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 70727 INFO  (coreLoadExecutor-313-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1561783132539060224
   [junit4]   2> 70728 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 70728 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 70728 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 70729 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 70731 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 70733 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 70735 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 70737 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 70738 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1db38d3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 71833 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 71835 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 71836 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@19ddd0d{/,null,AVAILABLE}
   [junit4]   2> 71836 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@14287da{HTTP/1.1,[http/1.1]}{127.0.0.1:৪৪০৩৮}
   [junit4]   2> 71836 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] o.e.j.s.Server 
Started @৭৩৭৫৭ms
   [junit4]   2> 71836 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=44038, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3CB1719F7C30D0-001/tempDir-001/shard0/cores}
   [junit4]   2> 71836 ERROR 
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 71837 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.5.0
   [junit4]   2> 71837 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 71837 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 71837 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-13T19:14:22.409315Z
   [junit4]   2> 71837 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 71844 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 71946 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/shard0/cores
   [junit4]   2> 71946 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 71948 INFO  (coreLoadExecutor-324-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> 72024 WARN  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 72025 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
   [junit4]   2> 72047 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 72123 WARN  (coreLoadExecutor-324-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> 72125 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 72153 INFO  (coreLoadExecutor-324-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> 72154 INFO  (coreLoadExecutor-324-thread-1) [    ] 
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_3CB1719F7C30D0-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 72154 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@11af356
   [junit4]   2> 72155 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=18, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4441498295559708]
   [junit4]   2> 72324 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 72324 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 72328 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 72328 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 72328 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 72329 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1689b7[collection1] main]
   [junit4]   2> 72329 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 72385 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 72695 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 72696 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 72697 INFO  (coreLoadExecutor-324-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1561783134604754944
   [junit4]   2> 72697 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 72697 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 72697 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 72699 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 72701 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 72707 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 72709 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 72711 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 72712 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1689b7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 72975 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 72981 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1561783134899404800)} 0 3
   [junit4]   2> 72987 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1561783134904647680)} 0 3
   [junit4]   2> 73008 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1561783134911987712)]} 0 18
   [junit4]   2> 73017 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1561783134934007808)]} 0 6
   [junit4]   2> 73022 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1561783134943444992)]} 0 2
   [junit4]   2> 73028 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1561783134948687872)]} 0 3
   [junit4]   2> 73033 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1561783134953930752)]} 0 3
   [junit4]   2> 73037 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1561783134959173632)]} 0 1
   [junit4]   2> 73041 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1561783134963367936)]} 0 1
   [junit4]   2> 73046 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1561783134967562240)]} 0 2
   [junit4]   2> 73049 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1561783134972805120)]} 0 1
   [junit4]   2> 73053 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1561783134975950848)]} 0 1
   [junit4]   2> 73056 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1561783134980145152)]} 0 1
   [junit4]   2> 73059 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1561783134983290880)]} 0 1
   [junit4]   2> 73062 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1561783134986436608)]} 0 1
   [junit4]   2> 73066 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1561783134990630912)]} 0 1
   [junit4]   2> 73069 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1561783134993776640)]} 0 1
   [junit4]   2> 73073 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1561783134997970944)]} 0 2
   [junit4]   2> 73077 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1561783135002165248)]} 0 1
   [junit4]   2> 73080 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1561783135005310976)]} 0 1
   [junit4]   2> 73082 INFO  (qtp22183374-811) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 73082 INFO  (qtp22183374-811) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18a20f0
   [junit4]   2> 73124 INFO  (qtp22183374-811) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@a52c16[collection1] main]
   [junit4]   2> 73125 INFO  (qtp22183374-811) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 73127 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a52c16[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C9)))}
   [junit4]   2> 73127 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 45
   [junit4]   2> 73130 INFO  (qtp26704786-828) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 73130 INFO  (qtp26704786-828) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@810a11
   [junit4]   2> 73144 INFO  (qtp26704786-828) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@81f4f7[collection1] main]
   [junit4]   2> 73145 INFO  (qtp26704786-828) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 73147 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@81f4f7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C9)))}
   [junit4]   2> 73147 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 18
   [junit4]   2> 73155 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1561783135079759872)]} 0 5
   [junit4]   2> 73161 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1561783135087099904)]} 0 4
   [junit4]   2> 73165 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1561783135093391360)]} 0 1
   [junit4]   2> 73169 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1561783135097585664)]} 0 2
   [junit4]   2> 73172 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1561783135102828544)]} 0 1
   [junit4]   2> 73175 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1561783135104925696)]} 0 1
   [junit4]   2> 73178 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1561783135108071424)]} 0 1
   [junit4]   2> 73182 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1561783135111217152)]} 0 1
   [junit4]   2> 73186 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1561783135115411456)]} 0 1
   [junit4]   2> 73189 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1561783135119605760)]} 0 1
   [junit4]   2> 73192 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1561783135122751488)]} 0 0
   [junit4]   2> 73195 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1561783135125897216)]} 0 1
   [junit4]   2> 73198 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1561783135129042944)]} 0 0
   [junit4]   2> 73200 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1561783135132188672)]} 0 0
   [junit4]   2> 73203 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1561783135134285824)]} 0 0
   [junit4]   2> 73205 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1561783135137431552)]} 0 0
   [junit4]   2> 73209 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1561783135140577280)]} 0 1
   [junit4]   2> 73212 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1561783135143723008)]} 0 1
   [junit4]   2> 73215 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1561783135146868736)]} 0 0
   [junit4]   2> 73217 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1561783135150014464)]} 0 0
   [junit4]   2> 73220 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1561783135153160192)]} 0 0
   [junit4]   2> 73223 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1561783135155257344)]} 0 0
   [junit4]   2> 73226 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1561783135158403072)]} 0 0
   [junit4]   2> 73228 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1561783135160500224)]} 0 0
   [junit4]   2> 73231 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1561783135163645952)]} 0 1
   [junit4]   2> 73234 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1561783135166791680)]} 0 0
   [junit4]   2> 73236 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1561783135169937408)]} 0 0
   [junit4]   2> 73239 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1561783135172034560)]} 0 0
   [junit4]   2> 73241 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1561783135175180288)]} 0 0
   [junit4]   2> 73244 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1561783135177277440)]} 0 0
   [junit4]   2> 73247 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1561783135180423168)]} 0 0
   [junit4]   2> 73249 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1561783135183568896)]} 0 0
   [junit4]   2> 73252 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1561783135185666048)]} 0 0
   [junit4]   2> 73254 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1561783135188811776)]} 0 0
   [junit4]   2> 73257 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1561783135191957504)]} 0 0
   [junit4]   2> 73260 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1561783135194054656)]} 0 0
   [junit4]   2> 73262 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1561783135196151808)]} 0 0
   [junit4]   2> 73264 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1561783135199297536)]} 0 0
   [junit4]   2> 73267 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1561783135202443264)]} 0 1
   [junit4]   2> 73270 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1561783135204540416)]} 0 0
   [junit4]   2> 73272 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1561783135207686144)]} 0 0
   [junit4]   2> 73275 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1561783135209783296)]} 0 0
   [junit4]   2> 73277 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1561783135212929024)]} 0 0
   [junit4]   2> 73280 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1561783135215026176)]} 0 0
   [junit4]   2> 73282 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1561783135218171904)]} 0 0
   [junit4]   2> 73285 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1561783135220269056)]} 0 0
   [junit4]   2> 73287 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1561783135223414784)]} 0 0
   [junit4]   2> 73290 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1561783135225511936)]} 0 0
   [junit4]   2> 73292 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1561783135228657664)]} 0 0
   [junit4]   2> 73295 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1561783135230754816)]} 0 0
   [junit4]   2> 73298 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1561783135233900544)]} 0 0
   [junit4]   2> 73300 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1561783135237046272)]} 0 0
   [junit4]   2> 73303 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1561783135239143424)]} 0 0
   [junit4]   2> 73305 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1561783135242289152)]} 0 0
   [junit4]   2> 73308 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1561783135244386304)]} 0 0
   [junit4]   2> 73310 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1561783135247532032)]} 0 0
   [junit4]   2> 73313 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1561783135249629184)]} 0 0
   [junit4]   2> 73316 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1561783135252774912)]} 0 0
   [junit4]   2> 73318 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1561783135254872064)]} 0 0
   [junit4]   2> 73320 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1561783135258017792)]} 0 0
   [junit4]   2> 73323 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1561783135260114944)]} 0 0
   [junit4]   2> 73325 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1561783135263260672)]} 0 0
   [junit4]   2> 73328 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1561783135265357824)]} 0 0
   [junit4]   2> 73331 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1561783135268503552)]} 0 1
   [junit4]   2> 73334 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1561783135271649280)]} 0 0
   [junit4]   2> 73336 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1561783135273746432)]} 0 0
   [junit4]   2> 73339 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1561783135276892160)]} 0 1
   [junit4]   2> 73341 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1561783135280037888)]} 0 0
   [junit4]   2> 73344 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1561783135282135040)]} 0 0
   [junit4]   2> 73346 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1561783135285280768)]} 0 0
   [junit4]   2> 73349 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1561783135287377920)]} 0 0
   [junit4]   2> 73351 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1561783135290523648)]} 0 0
   [junit4]   2> 73354 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1561783135292620800)]} 0 0
   [junit4]   2> 73356 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1561783135295766528)]} 0 0
   [junit4]   2> 73359 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1561783135297863680)]} 0 0
   [junit4]   2> 73361 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1561783135299960832)]} 0 0
   [junit4]   2> 73364 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1561783135303106560)]} 0 0
   [junit4]   2> 73367 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1561783135306252288)]} 0 0
   [junit4]   2> 73369 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1561783135308349440)]} 0 0
   [junit4]   2> 73372 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1561783135311495168)]} 0 0
   [junit4]   2> 73374 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1561783135314640896)]} 0 0
   [junit4]   2> 73377 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1561783135317786624)]} 0 0
   [junit4]   2> 73380 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1561783135319883776)]} 0 0
   [junit4]   2> 73382 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1561783135323029504)]} 0 0
   [junit4]   2> 73385 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1561783135325126656)]} 0 0
   [junit4]   2> 73388 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1561783135328272384)]} 0 0
   [junit4]   2> 73390 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1561783135330369536)]} 0 0
   [junit4]   2> 73393 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1561783135333515264)]} 0 1
   [junit4]   2> 73395 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1561783135336660992)]} 0 0
   [junit4]   2> 73398 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1561783135338758144)]} 0 0
   [junit4]   2> 73400 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1561783135341903872)]} 0 0
   [junit4]   2> 73402 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1561783135344001024)]} 0 0
   [junit4]   2> 73405 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1561783135346098176)]} 0 0
   [junit4]   2> 73407 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1561783135349243904)]} 0 0
   [junit4]   2> 73409 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1561783135351341056)]} 0 0
   [junit4]   2> 73412 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1561783135353438208)]} 0 0
   [junit4]   2> 73415 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1561783135356583936)]} 0 1
   [junit4]   2> 73418 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1561783135359729664)]} 0 0
   [junit4]   2> 73420 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1561783135362875392)]} 0 0
   [junit4]   2> 73423 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1561783135364972544)]} 0 1
   [junit4]   2> 73427 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1561783135368118272)]} 0 1
   [junit4]   2> 73449 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1561783135392235520)]} 0 1
   [junit4]   2> 73452 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1561783135396429824)]} 0 0
   [junit4]   2> 73455 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1561783135399575552)]} 0 0
   [junit4]   2> 73458 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1561783135401672704)]} 0 0
   [junit4]   2> 73461 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1561783135405867008)]} 0 0
   [junit4]   2> 73464 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1561783135409012736)]} 0 0
   [junit4]   2> 73466 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1561783135411109888)]} 0 0
   [junit4]   2> 73469 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1561783135413207040)]} 0 0
   [junit4]   2> 73472 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1561783135417401344)]} 0 0
   [junit4]   2> 73475 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1561783135419498496)]} 0 0
   [junit4]   2> 73477 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1561783135422644224)]} 0 0
   [junit4]   2> 73480 INFO  (qtp22183374-804) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1561783135424741376)]} 0 0
   [junit4]   2> 73482 INFO  (qtp26704786-821) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1561783135427887104)]} 0 0
   [junit4]   2> 73485 INFO  (qtp22183374-809) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1561783135431032832)]} 0 0
   [junit4]   2> 73488 INFO  (qtp26704786-826) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1561783135433129984)]} 0 0
   [junit4]   2> 73490 INFO  (qtp22183374-810) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1561783135435227136)]} 0 0
   [junit4]   2> 73492 INFO  (qtp26704786-827) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1561783135438372864)]} 0 0
   [junit4]   2> 73495 INFO  (qtp22183374-811) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 73495 INFO  (qtp22183374-811) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18a20f0
   [junit4]   2> 73528 INFO  (qtp22183374-811) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@1f0fe79[collection1] main]
   [junit4]   2> 73529 INFO  (qtp22183374-811) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 73532 INFO  (searcherExecutor-314-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f0fe79[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C9)
 Uninverting(_1(6.5.0):C59)))}
   [junit4]   2> 73532 INFO  (qtp22183374-811) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 37
   [junit4]   2> 73535 INFO  (qtp26704786-828) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 73535 INFO  (qtp26704786-828) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@810a11
   [junit4]   2> 73563 INFO  (qtp26704786-828) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@118ac96[collection1] main]
   [junit4]   2> 73563 INFO  (qtp26704786-828) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 73565 INFO  (searcherExecutor-325-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@118ac96[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C9)
 Uninverting(_1(6.5.0):C59)))}
   [junit4]   2> 73565 INFO  (qtp26704786-828) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 30
   [junit4]   2> 73569 INFO  (qtp22183374-804) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 73569 INFO  (qtp22183374-804) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=1
   [junit4]   2> 73571 INFO  (qtp22183374-809) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 73571 INFO  (qtp22183374-809) [    ] 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> 73580 INFO  (qtp22183374-810) [    ] 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=6
   [junit4]   2> 73985 INFO  (qtp26704786-827) [    ] 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::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464158&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 73995 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464158&ids=132,110,122,111,101,113,15,17,140,141&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 74002 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:44038//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=416
   [junit4]   2> 74007 INFO  (qtp22183374-811) [    ] 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=1
   [junit4]   2> 74016 INFO  (qtp26704786-822) [    ] 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=http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1489432464585&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74021 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464585&ids=143,100,112,102,125,103,127,108,109,131&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74028 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=14
   [junit4]   2> 74034 INFO  (qtp22183374-804) [    ] 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=2
   [junit4]   2> 74044 INFO  (qtp26704786-821) [    ] 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:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1489432464613&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 74051 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464613&ids=132,100,134,135,3,15,114,148,126,6&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 74056 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=15
   [junit4]   2> 74060 INFO  (qtp22183374-809) [    ] 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> 74069 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1489432464638&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74075 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464638&ids=144,123,14,113,103,125,147,4,106,107&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 74078 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 74084 INFO  (qtp22183374-810) [    ] 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=2
   [junit4]   2> 74116 INFO  (qtp26704786-827) [    ] 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=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464660&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 74121 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464660&ids=121,111,134,145,113,15,5,105,6,139&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74124 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:44038//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=36
   [junit4]   2> 74128 INFO  (qtp22183374-811) [    ] 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> 74137 INFO  (qtp26704786-822) [    ] 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:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464704&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74150 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464704&ids=143,100,12,13,102,148,104,17,128,108&isShard=true&shard.url=http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74152 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=20
   [junit4]   2> 74159 INFO  (qtp22183374-811) [    ] 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=1
   [junit4]   2> 74168 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464736&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 74172 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464736&ids=132,12,124,4,103,115,126,17,141,120&isShard=true&shard.url=http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 74176 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 74181 INFO  (qtp22183374-809) [    ] 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> 74187 INFO  (qtp26704786-826) [    ] 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::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1489432464756&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74196 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464756&ids=143,11,144,15,5,127,105,6,139,130&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74198 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=14
   [junit4]   2> 74204 INFO  (qtp22183374-809) [    ] 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=2
   [junit4]   2> 74210 INFO  (qtp26704786-827) [    ] 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=[ff01::083]:33332/|http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464779&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74214 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464779&ids=11,12,144,123,113,3,4,114,17,118&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74217 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44038//collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 74220 INFO  (qtp22183374-804) [    ] 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> 74228 INFO  (qtp26704786-821) [    ] 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::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1489432464796&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 74231 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464796&ids=110,121,136,15,104,148,128,129,130,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74232 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 74236 INFO  (qtp22183374-811) [    ] 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=1
   [junit4]   2> 74242 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1489432464812&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74246 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464812&ids=144,14,124,113,125,115,149,6,127,8&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74247 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74250 INFO  (qtp22183374-810) [    ] 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> 74258 INFO  (qtp26704786-822) [    ] 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:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464826&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74262 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464826&ids=132,11,12,133,145,146,16,148,108,131&isShard=true&shard.url=http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74264 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 74268 INFO  (qtp22183374-809) [    ] 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> 74274 INFO  (qtp26704786-827) [    ] 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:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464843&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74279 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464843&ids=110,102,146,147,114,1001,128,107,9,119&isShard=true&shard.url=http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 74281 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 74284 INFO  (qtp22183374-804) [    ] 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> 74291 INFO  (qtp26704786-821) [    ] 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::213]:33332/|http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1489432464860&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74294 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464860&ids=11,2,123,112,101,145,13,3,136,117&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74295 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74300 INFO  (qtp22183374-811) [    ] 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=1
   [junit4]   2> 74306 INFO  (qtp26704786-828) [    ] 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:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1489432464875&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74309 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464875&ids=110,134,102,14,104,115,116,139,117,142&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74311 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 74314 INFO  (qtp22183374-810) [    ] 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> 74319 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464889&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74323 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464889&ids=132,145,101,17,149,1001,128,8,109,130&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 74325 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:44038//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74327 INFO  (qtp22183374-809) [    ] 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> 74333 INFO  (qtp26704786-827) [    ] 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::213]:33332/|http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1489432464902&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74336 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464902&ids=100,112,104,126,17,128,1001,129,9,141&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74338 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:44038//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 74342 INFO  (qtp22183374-804) [    ] 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> 74348 INFO  (qtp26704786-821) [    ] 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=[ff01::114]:33332/|http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432464917&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74352 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464917&ids=121,13,113,135,147,114,125,16,116,117&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74353 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:44038//collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74356 INFO  (qtp22183374-811) [    ] 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=1
   [junit4]   2> 74366 INFO  (qtp26704786-828) [    ] 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:44038//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1489432464933&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 74381 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464933&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74384 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::083]:33332/&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=23
   [junit4]   2> 74394 INFO  (qtp22183374-810) [    ] 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=7
   [junit4]   2> 74402 INFO  (qtp26704786-822) [    ] 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:44038//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1489432464971&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74406 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432464971&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74407 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 74419 INFO  (qtp22183374-809) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=a_i1[*+TO+*]&distrib=false&sort=a_i1+asc&wt=javabin&version=2} 
hits=13 status=0 QTime=8
   [junit4]   2> 74425 INFO  (qtp26704786-827) [    ] 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:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=a_i1[*+TO+*]&NOW=1489432464994&isShard=true&wt=javabin}
 hits=13 status=0 QTime=1
   [junit4]   2> 74429 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=a_i1[*+TO+*]&distrib=false&shards.purpose=64&NOW=1489432464994&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74430 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=a_i1[*+TO+*]&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=a_i1+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=8
   [junit4]   2> 74435 INFO  (qtp22183374-804) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=2
   [junit4]   2> 74441 INFO  (qtp26704786-821) [    ] 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,+id+desc&shard.url=http://127.0.0.1:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432465011&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74445 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432465011&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74446 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1&sort=a_i1+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74449 INFO  (qtp22183374-811) [    ] 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> 74456 INFO  (qtp26704786-828) [    ] 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=[ff01::114]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1489432465025&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74458 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1489432465025&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74460 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::083]:33332/&fl=*,score&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74464 INFO  (qtp22183374-810) [    ] 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> 74471 INFO  (qtp26704786-822) [    ] 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:44038//collection1&rows=10&version=2&q=*:*&NOW=1489432465040&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74474 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1489432465040&ids=11,2,123,112,101,145,13,3,136,117&isShard=true&shard.url=http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74475 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74478 INFO  (qtp22183374-809) [    ] 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> 74484 INFO  (qtp26704786-827) [    ] 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:44038//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1489432465053&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 74489 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489432465053&ids=110,102,146,147,114,1001,128,107,9,119&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74492 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 74496 INFO  (qtp22183374-804) [    ] 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=1
   [junit4]   2> 74517 INFO  (qtp26704786-821) [    ] 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=1489432465086&start=0&fsv=true&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44038//collection1&rows=10&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 74523 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&shards.purpose=64&NOW=1489432465086&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74525 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q={!func}a_i1&shards=[ff01::114]:33332/|http://127.0.0.1:44038//collection1&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 74528 INFO  (qtp22183374-811) [    ] 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> 74533 INFO  (qtp26704786-826) [    ] 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=1489432465103&start=0&fsv=true&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&rows=10&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 74537 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q={!func}a_i1&distrib=false&fl=*,score&shards.purpose=64&NOW=1489432465103&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74538 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q={!func}a_i1&shards=[ff01::083]:33332/|http://127.0.0.1:44038//collection1|[ff01::213]:33332/&fl=*,score&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 74548 INFO  (qtp22183374-811) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=quick&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=5
   [junit4]   2> 74554 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=quick&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1489432465122&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&rows=10&wt=javabin&version=2}
 hits=2 status=0 QTime=0
   [junit4]   2> 74567 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=quick&distrib=false&shards.purpose=64&NOW=1489432465122&ids=4,5&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74571 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=quick&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 hits=2 status=0 QTime=21
   [junit4]   2> 74576 INFO  (qtp22183374-809) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=id&start=0&wt=javabin&version=2} hits=2 status=0 
QTime=1
   [junit4]   2> 74591 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=id&fl=score&shards.purpose=68&NOW=1489432465159&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&wt=javabin&version=2}
 hits=2 status=0 QTime=1
   [junit4]   2> 74592 INFO  (qtp26704786-826) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&fl=id&start=0&wt=javabin&version=2}
 hits=2 status=0 QTime=4
   [junit4]   2> 74595 INFO  (qtp22183374-804) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=foofoofoo&start=0&wt=javabin&version=2} hits=2 
status=0 QTime=0
   [junit4]   2> 74600 INFO  (qtp26704786-827) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1489432465169&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&wt=javabin&version=2}
 hits=2 status=0 QTime=0
   [junit4]   2> 74603 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=foofoofoo&fl=id&shards.purpose=64&NOW=1489432465169&start=0&ids=1,8&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 74604 INFO  (qtp26704786-822) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&shards=http://127.0.0.1:44038//collection1|[ff01::213]:33332/|[ff01::114]:33332/&fl=foofoofoo&start=0&wt=javabin&version=2}
 hits=2 status=0 QTime=6
   [junit4]   2> 74606 INFO  (qtp22183374-804) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=id&start=100&wt=javabin&version=2} hits=2 
status=0 QTime=0
   [junit4]   2> 74612 INFO  (qtp26704786-828) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&distrib=false&fl=id&fl=score&shards.purpose=68&NOW=1489432465180&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=110&wt=javabin&version=2}
 hits=2 status=0 QTime=0
   [junit4]   2> 74612 INFO  (qtp26704786-821) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={q=all&shards=http://127.0.0.1:44038//collection1|[ff01::114]:33332/|[ff01::213]:33332/&fl=id&start=100&wt=javabin&version=2}
 hits=2 status=0 QTime=4
   [junit4]   2> 74615 INFO  (qtp22183374-811) [    ] o.a.s.c.S.Request 
[collection1] 

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

27.0.0.1:42876//collection1|[ff01::114]:33332/&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1489432584180&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=6
   [junit4]   2> 193617 INFO  (qtp27009778-956) [    ] o.a.s.c.S.Request 
[collection1]  webapp= path=/select 
params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:42747//collection1|[ff01::213]:33332/|[ff01::083]:33332/,http://127.0.0.1:34396//collection1|[ff01::213]:33332/,[ff01::213]:33332/|http://127.0.0.1:42876//collection1|[ff01::114]:33332/&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=9
   [junit4]   2> 193618 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 193619 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@1c1b185{HTTP/1.1,[http/1.1]}{127.0.0.1:০}
   [junit4]   2> 193619 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=4307587
   [junit4]   2> 193619 INFO  (coreCloseExecutor-418-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1547a46
   [junit4]   2> 193795 INFO  (coreCloseExecutor-418-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 193796 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 193796 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@4eff3{/,null,UNAVAILABLE}
   [junit4]   2> 193798 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@d88fb2{HTTP/1.1,[http/1.1]}{127.0.0.1:০}
   [junit4]   2> 193798 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=6740436
   [junit4]   2> 193799 INFO  (coreCloseExecutor-420-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@9875c7
   [junit4]   2> 193933 INFO  (coreCloseExecutor-420-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 193934 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 193935 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@174b392{/,null,UNAVAILABLE}
   [junit4]   2> 193936 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@162ad34{HTTP/1.1,[http/1.1]}{127.0.0.1:০}
   [junit4]   2> 193936 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=21163682
   [junit4]   2> 193936 INFO  (coreCloseExecutor-422-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@11156e
   [junit4]   2> 193990 INFO  (coreCloseExecutor-422-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 193990 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 193991 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@159709f{/,null,UNAVAILABLE}
   [junit4]   2> 193992 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@4cde7{HTTP/1.1,[http/1.1]}{127.0.0.1:০}
   [junit4]   2> 193992 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=30070237
   [junit4]   2> 193993 INFO  (coreCloseExecutor-424-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@522444
   [junit4]   2> 194026 INFO  (coreCloseExecutor-424-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 194027 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 194027 INFO  
(TEST-TestDistributedSearch.test-seed#[3CB1719F7C30D0]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@26db12{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=3CB1719F7C30D0 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=bn-BD -Dtests.timezone=Etc/GMT+5 -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE  125s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3CB1719F7C30D0:88688EAB31805D28]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1176)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1117)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:977)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:547)
   [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.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 194033 INFO  
(SUITE-TestDistributedSearch-seed#[3CB1719F7C30D0]-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_3CB1719F7C30D0-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{other_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 range_facet_l_dv=PostingsFormat(name=Memory doPackFST= true), 
SubjectTerms_mfacet=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_l1=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_n_tdt=BlockTreeOrds(blocksize=128), 
multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), 
intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 
n_td1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_d1=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_f1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 a_s=BlockTreeOrds(blocksize=128), oddField_s=PostingsFormat(name=Memory 
doPackFST= true), n_tf1=PostingsFormat(name=LuceneVarGapFixedInterval), 
lowerfilt1and2=PostingsFormat(name=LuceneVarGapFixedInterval), 
a_t=PostingsFormat(name=LuceneVarGapFixedInterval), 
lowerfilt=PostingsFormat(name=LuceneVarGapFixedInterval), 
id=PostingsFormat(name=Memory doPackFST= true), 
text=BlockTreeOrds(blocksize=128), 
timestamp=PostingsFormat(name=LuceneVarGapFixedInterval), 
severity=BlockTreeOrds(blocksize=128), 
foo_sev_enum=BlockTreeOrds(blocksize=128), 
n_dt1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), 
b_n_tdt=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 n_ti1=PostingsFormat(name=Memory doPackFST= true), 
rnd_b=PostingsFormat(name=Memory doPackFST= true), 
foo_b=BlockTreeOrds(blocksize=128), 
n_tdt1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 foo_d=PostingsFormat(name=Memory doPackFST= true), 
range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), 
foo_f=BlockTreeOrds(blocksize=128)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), 
foo_sev_enum=DocValuesFormat(name=Lucene54), 
_version_=DocValuesFormat(name=Memory), 
range_facet_i_dv=DocValuesFormat(name=Lucene54), 
intDvoDefault=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1372, 
maxMBSortInHeap=7.690850251956386, 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=bn-BD, 
timezone=Etc/GMT+5
   [junit4]   2> NOTE: Linux 4.4.0-66-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=2,free=37302592,total=208666624
   [junit4]   2> NOTE: All tests run in this JVM: [TestDocumentBuilder, 
ReplaceNodeTest, SimpleCollectionCreateDeleteTest, SolrRequestParserTest, 
ZkNodePropsTest, PKIAuthenticationIntegrationTest, TestSolrCoreProperties, 
TestRestoreCore, OverseerRolesTest, TestSearcherReuse, TestComponentsName, 
TestDistributedSearch]
   [junit4] Completed [82/697 (1!)] on J0 in 124.80s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 53775 lines...]


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