[JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_121) - Build # 806 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/806/
Java: 64bit/jdk1.8.0_121 -XX:-UseCompressedOops -XX:+UseSerialGC

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([BA1263625F147996:32465CB8F1E8146E]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11327 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\init-core-data-001
   [junit4]   2> 506255 WARN  
(SUITE-TestDistributedSearch-seed#[BA1263625F147996]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 506256 INFO  
(SUITE-TestDistributedSearch-seed#[BA1263625F147996]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 506267 INFO  
(SUITE-TestDistributedSearch-seed#[BA1263625F147996]-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> 506267 INFO  
(SUITE-TestDistributedSearch-seed#[BA1263625F147996]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_rt/kx
   [junit4]   2> 507337 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\control\cores\collection1
   [junit4]   2> 507340 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 507341 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@78b0d415{/_rt/kx,null,AVAILABLE}
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2ac03293{HTTP/1.1,[http/1.1]}{127.0.0.1:50122}
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] o.e.j.s.Server 
Started @511958ms
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_rt/kx, 
hostPort=50122, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\control\cores}
   [junit4]   2> 507345 ERROR 
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.6.0
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 507345 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-26T19:31:15.438Z
   [junit4]   2> 507346 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\control\solr.xml
   [junit4]   2> 507382 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 507502 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\control\cores
   [junit4]   2> 507502 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 507580 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 507654 WARN  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 507656 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 507694 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 507802 WARN  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 507804 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 507824 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 507824 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 507824 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\control\cores\collection1\data\]
   [junit4]   2> 507825 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d5ba8bb
   [junit4]   2> 507826 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=792724844, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 508000 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 508000 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 508002 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 508002 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 508002 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 508003 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2ba6ac0e[collection1] main]
   [junit4]   2> 508003 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 508013 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 508078 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 508079 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 508080 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 508080 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 508080 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 508081 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 508083 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 508085 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 508085 INFO  (coreLoadExecutor-1812-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1562961957280022528
   [junit4]   2> 508085 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 508086 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 508088 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2ba6ac0e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 509152 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\shard0\cores\collection1
   [junit4]   2> 509157 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 509159 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@53a93610{/_rt/kx,null,AVAILABLE}
   [junit4]   2> 509160 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@228aaa77{HTTP/1.1,[http/1.1]}{127.0.0.1:50145}
   [junit4]   2> 509161 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] o.e.j.s.Server 
Started @513774ms
   [junit4]   2> 509161 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_rt/kx, 
hostPort=50145, 
coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\shard0\cores}
   [junit4]   2> 509162 ERROR 
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 509169 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.6.0
   [junit4]   2> 509169 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 509169 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 509169 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-26T19:31:17.262Z
   [junit4]   2> 509173 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\shard0\solr.xml
   [junit4]   2> 509202 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 509451 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\shard0\cores
   [junit4]   2> 509451 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 509463 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 509581 WARN  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 509584 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 509618 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 509882 WARN  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 509884 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 509911 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 509912 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 509912 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001\tempDir-001\shard0\cores\collection1\data\]
   [junit4]   2> 509912 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d5ba8bb
   [junit4]   2> 509914 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=792724844, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 510360 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 510360 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 510361 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 510361 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 510362 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 510364 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@2428bccb[collection1] main]
   [junit4]   2> 510364 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 510375 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 510438 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 510438 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 510439 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 510439 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 510439 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 510440 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 510440 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 510442 INFO  (coreLoadExecutor-1823-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1562961959751516160
   [junit4]   2> 510444 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 510445 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 510445 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 510447 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2428bccb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 510875 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 510883 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1562961960209743872)} 0 3
   [junit4]   2> 510891 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1562961960218132480)} 0 4
   [junit4]   2> 510900 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[1 (1562961960226521088)]} 0 4
   [junit4]   2> 510907 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[1 (1562961960233861120)]} 0 4
   [junit4]   2> 510915 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[2 (1562961960245395456)]} 0 1
   [junit4]   2> 510920 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[2 (1562961960250638336)]} 0 2
   [junit4]   2> 510922 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[3 (1562961960253784064)]} 0 1
   [junit4]   2> 510926 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[3 (1562961960257978368)]} 0 1
   [junit4]   2> 510930 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[4 (1562961960261124096)]} 0 1
   [junit4]   2> 510934 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[4 (1562961960265318400)]} 0 1
   [junit4]   2> 510936 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[5 (1562961960268464128)]} 0 1
   [junit4]   2> 510941 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[5 (1562961960272658432)]} 0 2
   [junit4]   2> 510945 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[6 (1562961960277901312)]} 0 1
   [junit4]   2> 510949 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[6 (1562961960282095616)]} 0 1
   [junit4]   2> 510954 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[7 (1562961960286289920)]} 0 1
   [junit4]   2> 510957 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[7 (1562961960290484224)]} 0 1
   [junit4]   2> 510961 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[8 (1562961960294678528)]} 0 1
   [junit4]   2> 510964 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[8 (1562961960297824256)]} 0 1
   [junit4]   2> 510967 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[9 (1562961960300969984)]} 0 0
   [junit4]   2> 510971 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[9 (1562961960305164288)]} 0 0
   [junit4]   2> 510973 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 510973 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@37ef1f19
   [junit4]   2> 510987 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1f615050[collection1] main]
   [junit4]   2> 510988 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 510990 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f615050[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)))}
   [junit4]   2> 510990 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 17
   [junit4]   2> 510993 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 510993 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@679c409b
   [junit4]   2> 510999 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@c5b8bf[collection1] main]
   [junit4]   2> 510999 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 511002 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@c5b8bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)))}
   [junit4]   2> 511002 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 10
   [junit4]   2> 511010 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[10 (1562961960341864448)]} 0 5
   [junit4]   2> 511017 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[10 (1562961960349204480)]} 0 5
   [junit4]   2> 511021 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[11 (1562961960357593088)]} 0 1
   [junit4]   2> 511027 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[11 (1562961960362835968)]} 0 1
   [junit4]   2> 511030 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[12 (1562961960367030272)]} 0 1
   [junit4]   2> 511033 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[12 (1562961960370176000)]} 0 1
   [junit4]   2> 511037 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[13 (1562961960374370304)]} 0 1
   [junit4]   2> 511041 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[13 (1562961960378564608)]} 0 1
   [junit4]   2> 511044 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[1001 (1562961960381710336)]} 0 1
   [junit4]   2> 511048 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[1001 (1562961960385904640)]} 0 1
   [junit4]   2> 511053 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[14 (1562961960390098944)]} 0 1
   [junit4]   2> 511055 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[14 (1562961960393244672)]} 0 0
   [junit4]   2> 511057 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[15 (1562961960395341824)]} 0 0
   [junit4]   2> 511060 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[15 (1562961960398487552)]} 0 0
   [junit4]   2> 511062 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[16 (1562961960400584704)]} 0 0
   [junit4]   2> 511065 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[16 (1562961960403730432)]} 0 0
   [junit4]   2> 511068 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[17 (1562961960406876160)]} 0 0
   [junit4]   2> 511070 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[17 (1562961960408973312)]} 0 0
   [junit4]   2> 511073 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[100 (1562961960412119040)]} 0 0
   [junit4]   2> 511075 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[100 (1562961960414216192)]} 0 0
   [junit4]   2> 511077 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[101 (1562961960417361920)]} 0 0
   [junit4]   2> 511081 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[101 (1562961960420507648)]} 0 0
   [junit4]   2> 511082 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[102 (1562961960422604800)]} 0 0
   [junit4]   2> 511084 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[102 (1562961960424701952)]} 0 0
   [junit4]   2> 511088 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[103 (1562961960427847680)]} 0 1
   [junit4]   2> 511091 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[103 (1562961960430993408)]} 0 0
   [junit4]   2> 511093 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[104 (1562961960433090560)]} 0 0
   [junit4]   2> 511095 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[104 (1562961960435187712)]} 0 0
   [junit4]   2> 511105 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[105 (1562961960438333440)]} 0 7
   [junit4]   2> 511122 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[105 (1562961960448819200)]} 0 16
   [junit4]   2> 511126 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[106 (1562961960467693568)]} 0 1
   [junit4]   2> 511129 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[106 (1562961960470839296)]} 0 1
   [junit4]   2> 511133 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[107 (1562961960475033600)]} 0 0
   [junit4]   2> 511135 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[107 (1562961960477130752)]} 0 0
   [junit4]   2> 511137 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[108 (1562961960479227904)]} 0 0
   [junit4]   2> 511140 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[108 (1562961960482373632)]} 0 0
   [junit4]   2> 511142 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[109 (1562961960484470784)]} 0 0
   [junit4]   2> 511145 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[109 (1562961960488665088)]} 0 0
   [junit4]   2> 511149 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[110 (1562961960491810816)]} 0 0
   [junit4]   2> 511150 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[110 (1562961960493907968)]} 0 0
   [junit4]   2> 511153 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[111 (1562961960497053696)]} 0 0
   [junit4]   2> 511156 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[111 (1562961960499150848)]} 0 0
   [junit4]   2> 511159 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[112 (1562961960502296576)]} 0 0
   [junit4]   2> 511160 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[112 (1562961960504393728)]} 0 0
   [junit4]   2> 511164 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[113 (1562961960507539456)]} 0 0
   [junit4]   2> 511165 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[113 (1562961960509636608)]} 0 0
   [junit4]   2> 511168 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[114 (1562961960511733760)]} 0 0
   [junit4]   2> 511169 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[114 (1562961960513830912)]} 0 0
   [junit4]   2> 511173 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[115 (1562961960516976640)]} 0 0
   [junit4]   2> 511174 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[115 (1562961960519073792)]} 0 0
   [junit4]   2> 511176 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[116 (1562961960521170944)]} 0 0
   [junit4]   2> 511180 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[116 (1562961960523268096)]} 0 0
   [junit4]   2> 511181 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[117 (1562961960526413824)]} 0 0
   [junit4]   2> 511182 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[117 (1562961960527462400)]} 0 0
   [junit4]   2> 511190 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[118 (1562961960534802432)]} 0 0
   [junit4]   2> 511193 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[118 (1562961960537948160)]} 0 0
   [junit4]   2> 511194 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[119 (1562961960540045312)]} 0 0
   [junit4]   2> 511198 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[119 (1562961960543191040)]} 0 0
   [junit4]   2> 511203 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[120 (1562961960545288192)]} 0 4
   [junit4]   2> 511211 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[120 (1562961960551579648)]} 0 5
   [junit4]   2> 511213 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[121 (1562961960558919680)]} 0 1
   [junit4]   2> 511216 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[121 (1562961960562065408)]} 0 1
   [junit4]   2> 511218 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[122 (1562961960565211136)]} 0 0
   [junit4]   2> 511221 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[122 (1562961960568356864)]} 0 0
   [junit4]   2> 511224 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[123 (1562961960571502592)]} 0 0
   [junit4]   2> 511226 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[123 (1562961960573599744)]} 0 0
   [junit4]   2> 511228 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[124 (1562961960575696896)]} 0 0
   [junit4]   2> 511233 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[124 (1562961960578842624)]} 0 0
   [junit4]   2> 511235 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[125 (1562961960581988352)]} 0 0
   [junit4]   2> 511238 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[125 (1562961960585134080)]} 0 0
   [junit4]   2> 511239 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[126 (1562961960587231232)]} 0 0
   [junit4]   2> 511243 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[126 (1562961960590376960)]} 0 0
   [junit4]   2> 511244 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[127 (1562961960592474112)]} 0 0
   [junit4]   2> 511247 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[127 (1562961960595619840)]} 0 0
   [junit4]   2> 511248 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[128 (1562961960596668416)]} 0 0
   [junit4]   2> 511251 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[128 (1562961960598765568)]} 0 0
   [junit4]   2> 511254 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[129 (1562961960601911296)]} 0 0
   [junit4]   2> 511256 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[129 (1562961960604008448)]} 0 0
   [junit4]   2> 511258 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[130 (1562961960607154176)]} 0 0
   [junit4]   2> 511262 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[130 (1562961960610299904)]} 0 0
   [junit4]   2> 511264 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[131 (1562961960613445632)]} 0 0
   [junit4]   2> 511266 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[131 (1562961960615542784)]} 0 0
   [junit4]   2> 511271 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[132 (1562961960618688512)]} 0 0
   [junit4]   2> 511273 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[132 (1562961960621834240)]} 0 0
   [junit4]   2> 511276 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[133 (1562961960624979968)]} 0 0
   [junit4]   2> 511278 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[133 (1562961960627077120)]} 0 0
   [junit4]   2> 511280 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[134 (1562961960629174272)]} 0 0
   [junit4]   2> 511282 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[134 (1562961960631271424)]} 0 0
   [junit4]   2> 511318 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[135 (1562961960633368576)]} 0 34
   [junit4]   2> 511325 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[135 (1562961960671117312)]} 0 5
   [junit4]   2> 511329 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[136 (1562961960680554496)]} 0 1
   [junit4]   2> 511331 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[136 (1562961960682651648)]} 0 1
   [junit4]   2> 511334 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[137 (1562961960685797376)]} 0 0
   [junit4]   2> 511336 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[137 (1562961960688943104)]} 0 0
   [junit4]   2> 511338 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[138 (1562961960691040256)]} 0 0
   [junit4]   2> 511340 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[138 (1562961960693137408)]} 0 0
   [junit4]   2> 511342 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[139 (1562961960695234560)]} 0 0
   [junit4]   2> 511346 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[139 (1562961960698380288)]} 0 0
   [junit4]   2> 511348 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[140 (1562961960701526016)]} 0 0
   [junit4]   2> 511350 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[140 (1562961960703623168)]} 0 0
   [junit4]   2> 511353 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[141 (1562961960705720320)]} 0 0
   [junit4]   2> 511356 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[141 (1562961960708866048)]} 0 0
   [junit4]   2> 511358 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[142 (1562961960710963200)]} 0 0
   [junit4]   2> 511359 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[142 (1562961960713060352)]} 0 0
   [junit4]   2> 511362 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[143 (1562961960716206080)]} 0 0
   [junit4]   2> 511364 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[143 (1562961960718303232)]} 0 0
   [junit4]   2> 511366 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[144 (1562961960720400384)]} 0 0
   [junit4]   2> 511370 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[144 (1562961960724594688)]} 0 0
   [junit4]   2> 511372 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[145 (1562961960726691840)]} 0 0
   [junit4]   2> 511375 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[145 (1562961960729837568)]} 0 0
   [junit4]   2> 511378 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[146 (1562961960731934720)]} 0 0
   [junit4]   2> 511379 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[146 (1562961960734031872)]} 0 0
   [junit4]   2> 511383 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[147 (1562961960737177600)]} 0 0
   [junit4]   2> 511385 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[147 (1562961960739274752)]} 0 1
   [junit4]   2> 511389 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[148 (1562961960742420480)]} 0 0
   [junit4]   2> 511390 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[148 (1562961960745566208)]} 0 0
   [junit4]   2> 511392 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[149 (1562961960747663360)]} 0 0
   [junit4]   2> 511396 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={wt=javabin&version=2}{add=[149 (1562961960750809088)]} 0 0
   [junit4]   2> 511397 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 511397 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@37ef1f19
   [junit4]   2> 511427 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@37bc2e00[collection1] main]
   [junit4]   2> 511428 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 511430 INFO  
(searcherExecutor-1813-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@37bc2e00[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)
 Uninverting(_1(6.6.0):C15) Uninverting(_2(6.6.0):C15) 
Uninverting(_3(6.6.0):C15) Uninverting(_4(6.6.0):C14)))}
   [junit4]   2> 511430 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 32
   [junit4]   2> 511431 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 511431 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@679c409b
   [junit4]   2> 511441 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@112fcf8[collection1] main]
   [junit4]   2> 511443 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 511445 INFO  
(searcherExecutor-1824-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@112fcf8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)
 Uninverting(_1(6.6.0):C15) Uninverting(_2(6.6.0):C15) 
Uninverting(_3(6.6.0):C15) Uninverting(_4(6.6.0):C14)))}
   [junit4]   2> 511445 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_rt/kx path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 14
   [junit4]   2> 511448 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 511448 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 511450 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 511450 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 511454 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511462 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679550&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511466 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679550&ids=121,110,13,124,148,105,17,149,130,120&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511476 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=19
   [junit4]   2> 511480 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511487 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx 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:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679578&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511492 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679578&ids=133,100,112,145,135,146,114,115,1001,139&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511502 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=17
   [junit4]   2> 511506 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511513 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679601&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511518 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679601&ids=143,144,100,123,134,124,114,126,137,120&isShard=true&shard.url=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511523 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=15
   [junit4]   2> 511527 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511533 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx 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:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679623&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511537 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679623&ids=110,12,14,136,15,149,128,118,107,119&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511540 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 511545 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511553 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679642&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511556 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679642&ids=132,110,146,14,124,16,138,139,118,130&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511559 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=11
   [junit4]   2> 511563 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511569 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::213]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679660&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511571 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679660&ids=121,111,100,101,134,103,5,149,1001,108&isShard=true&shard.url=[ff01::213]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511575 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=[ff01::213]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 511578 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511584 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx 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:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679676&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511586 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679676&ids=12,123,126,138,127,106,118,8,140,10&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511590 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 511593 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511599 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679689&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511604 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679689&ids=1,144,2,102,147,103,137,1001,141,142&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511608 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 511611 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511615 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679706&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511618 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679706&ids=121,132,144,146,126,104,16,149,128,118&isShard=true&shard.url=[ff01::213]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511619 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=[ff01::213]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 511621 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511626 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679718&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511630 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679718&ids=143,100,2,125,6,17,106,8,130,10&isShard=true&shard.url=[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511632 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 511635 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511641 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx 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:50145/_rt/kx/collection1|[ff01::083]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679731&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511644 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679731&ids=143,2,15,125,17,7,106,107,9,130&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::083]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511645 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::083]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 511648 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511652 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx 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:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679744&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511655 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679744&ids=122,144,102,14,114,103,5,140,131,10&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511657 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 511659 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511665 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx 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:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679755&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511667 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679755&ids=1,145,102,3,136,115,16,138,149,129&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511669 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 511672 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511678 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679768&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511680 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679768&ids=132,2,103,137,105,17,106,8,109,140&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511681 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 511685 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511690 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679780&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511693 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679780&ids=11,135,124,4,105,127,138,117,130,142&isShard=true&shard.url=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511695 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 511698 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511702 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679793&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511705 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679793&ids=121,122,101,136,115,126,128,107,140,120&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511707 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 511710 INFO  (qtp821068499-3501) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511716 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679807&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511719 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679807&ids=143,1,112,103,105,138,127,116,128,129&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511721 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 511723 INFO  (qtp821068499-3496) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511727 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679819&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511730 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679819&ids=12,101,146,147,15,16,7,9,130,141&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511731 INFO  (qtp774724613-3522) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 511733 INFO  (qtp821068499-3494) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 511738 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+desc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679829&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511740 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679829&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511741 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx&sort=a_i_ni_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 511745 INFO  (qtp821068499-3498) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 511750 INFO  (qtp774724613-3513) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i_ni_p,5))+desc&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=*:*&NOW=1490556679841&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511753 INFO  (qtp774724613-3512) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679841&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511754 INFO  (qtp774724613-3523) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=http://127.0.0.1:50145/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 511756 INFO  (qtp821068499-3497) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=a_i_ni_p[*+TO+*]&distrib=false&sort=a_i_ni_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=0
   [junit4]   2> 511762 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+asc&shard.url=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&rows=10&version=2&q=a_i_ni_p[*+TO+*]&NOW=1490556679852&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 511764 INFO  (qtp774724613-3514) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=a_i_ni_p[*+TO+*]&distrib=false&shards.purpose=64&NOW=1490556679852&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511765 INFO  (qtp774724613-3516) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=a_i_ni_p[*+TO+*]&shards=[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&sort=a_i_ni_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=6
   [junit4]   2> 511768 INFO  (qtp821068499-3499) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 511785 INFO  (qtp774724613-3511) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+asc,+id+desc&shard.url=[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&rows=10&version=2&q=*:*&NOW=1490556679864&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 511787 INFO  (qtp774724613-3515) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1490556679864&ids=11,144,145,146,147,4,148,149,6,1001&isShard=true&shard.url=[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 511788 INFO  (qtp774724613-3521) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&shards=[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx|[ff01::083]:33332/_rt/kx|http://127.0.0.1:50145/_rt/kx/collection1&sort=a_i_ni_p+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=17
   [junit4]   2> 511791 INFO  (qtp821068499-3500) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i_ni_p+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 511797 INFO  (qtp774724613-3512) [    x:co

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

=11,101,13,2,15,4,17,6,1001,8&isShard=true&facet=false&wt=javabin} status=0 
QTime=0
   [junit4]   2> 612322 INFO  (qtp978291823-3588) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={facet.query=a_i_ni_p:[1+TO+50]&facet.query=a_i_ni_p:[1+TO+50]&q=*:*&shards=[ff01::083]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|http://127.0.0.1:50187/_rt/kx/collection1,http://127.0.0.1:50190/_rt/kx/collection1|[ff01::083]:33332/_rt/kx|[ff01::114]:33332/_rt/kx|[ff01::213]:33332/_rt/kx&shards.tolerant=true&shards.info=true&facet=true&wt=javabin&version=2}
 hits=70 status=0 QTime=10
   [junit4]   2> 612353 WARN  (qtp1878110056-3547) [    x:collection1] 
o.a.s.h.c.SearchHandler Query: 
distrib=false&fl=id,a_i_ni_p&group.limit=10&sort=a_i_ni_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
109234410838145 (System.nanoTime(): 109234439396664), 
TermsEnum=org.apache.lucene.codecs.blocktree.SegmentTermsEnum@666bd1dc
   [junit4]   2> 612354 INFO  (qtp1878110056-3547) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id,a_i_ni_p&group.limit=10&sort=a_i_ni_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=31
   [junit4]   2> 612413 WARN  (qtp271265257-3565) [    x:collection1] 
o.a.s.h.c.SearchHandler Query: 
distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i_ni_p+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:50187/_rt/kx/collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1490556780452&isShard=true&timeAllowed=1&wt=javabin&group=true;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
109234448851413 (System.nanoTime(): 109234499104034), 
TermsEnum=org.apache.lucene.codecs.blocktree.SegmentTermsEnum@19eec72
   [junit4]   2> 612414 INFO  (qtp271265257-3565) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i_ni_p+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:50187/_rt/kx/collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1490556780452&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=52
   [junit4]   2> 612424 INFO  (qtp271265257-3562) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_rt/kx path=/select 
params={fl=id,a_i_ni_p&group.limit=10&sort=a_i_ni_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=http://127.0.0.1:50187/_rt/kx/collection1,http://127.0.0.1:50190/_rt/kx/collection1|[ff01::213]:33332/_rt/kx&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=64
   [junit4]   2> 612425 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 612427 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@500cb934{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 612427 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1253832091
   [junit4]   2> 612432 INFO  (coreCloseExecutor-1866-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@102bce8c
   [junit4]   2> 612683 INFO  (coreCloseExecutor-1866-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 612684 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 612685 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7fd05fe8{/_rt/kx,null,UNAVAILABLE}
   [junit4]   2> 612690 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@2734266b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 612690 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1399210632
   [junit4]   2> 612692 INFO  (coreCloseExecutor-1868-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1f2d88ab
   [junit4]   2> 612788 INFO  (coreCloseExecutor-1868-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 612790 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 612790 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@168d6bb0{/_rt/kx,null,UNAVAILABLE}
   [junit4]   2> 612796 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@347919e9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 612797 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1751265045
   [junit4]   2> 612799 INFO  (coreCloseExecutor-1870-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3f8e401
   [junit4]   2> 612859 INFO  (coreCloseExecutor-1870-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 612860 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 612861 INFO  
(TEST-TestDistributedSearch.test-seed#[BA1263625F147996]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3b3c4cb3{/_rt/kx,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=BA1263625F147996 -Dtests.slow=true -Dtests.locale=ar-IQ 
-Dtests.timezone=Antarctica/South_Pole -Dtests.asserts=true 
-Dtests.file.encoding=Cp1252
   [junit4] FAILURE  107s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([BA1263625F147996:32465CB8F1E8146E]: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 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 612866 INFO  
(SUITE-TestDistributedSearch-seed#[BA1263625F147996]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_BA1263625F147996-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=ar-IQ, 
timezone=Antarctica/South_Pole
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_121 
(64-bit)/cpus=3,threads=1,free=89205232,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestSolrQueryParserDefaultOperatorResource, TestUninvertingReader, 
TestStressInPlaceUpdates, TestExtendedDismaxParser, TestXmlQParser, 
TestPseudoReturnFields, TestReplicationHandlerBackup, 
DocExpirationUpdateProcessorFactoryTest, AlternateDirectoryTest, 
TestRandomDVFaceting, DocumentAnalysisRequestHandlerTest, TestRTGBase, 
PeerSyncReplicationTest, ShardRoutingTest, TestNRTOpen, QueryResultKeyTest, 
SolrRequestParserTest, MultiThreadedOCPTest, TestMultiWordSynonyms, 
TestFieldCache, TestUseDocValuesAsStored2, RemoteQueryErrorTest, 
TestPostingsSolrHighlighter, SolrCoreMetricManagerTest, LargeFieldTest, 
DocValuesNotIndexedTest, SolrIndexMetricsTest, CdcrRequestHandlerTest, 
TestJsonRequest, CollectionsAPIAsyncDistributedZkTest, QueryEqualityTest, 
CdcrReplicationDistributedZkTest, StandardRequestHandlerTest, 
TestStressRecovery, TestCloudPseudoReturnFields, TestSolrIndexConfig, 
SchemaVersionSpecificBehaviorTest, ConnectionManagerTest, TestFieldResource, 
TestSolrCloudWithSecureImpersonation, DocValuesTest, PingRequestHandlerTest, 
SmileWriterTest, TestPerFieldSimilarityClassic, 
BigEndianAscendingWordDeserializerTest, 
DistributedQueryComponentCustomSortTest, TestClusterStateMutator, 
TestComponentsName, SharedFSAutoReplicaFailoverTest, TestLazyCores, 
TestWordDelimiterFilterFactory, TestCollationField, TestManagedSchemaAPI, 
HLLUtilTest, TestFastOutputStream, TestTolerantSearch, 
TestCorePropertiesReload, TestEmbeddedSolrServerConstructors, 
TestSchemaResource, TestFastLRUCache, XsltUpdateRequestHandlerTest, 
BlockCacheTest, DisMaxRequestHandlerTest, CurrencyFieldXmlFileTest, 
TestIBSimilarityFactory, TestDFISimilarityFactory, TestDistributedSearch]
   [junit4] Completed [115/701 (1!)] on J0 in 106.69s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 45195 lines...]


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