[JENKINS] Lucene-Solr-6.4-Linux (32bit/jdk1.8.0_121) - Build # 132 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.4-Linux/132/
Java: 32bit/jdk1.8.0_121 -server -XX:+UseConcMarkSweepGC

2 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([124B03B161F68B40:9A1F3C6BCF0AE6B8]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit

Error Message:
expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
        at 
__randomizedtesting.SeedInfo.seed([124B03B161F68B40:EB06901E5D83C6CA]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit(ShardSplitTest.java:284)
        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$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11721 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/init-core-data-001
   [junit4]   2> 814525 INFO  
(SUITE-TestDistributedSearch-seed#[124B03B161F68B40]-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> 814525 INFO  
(SUITE-TestDistributedSearch-seed#[124B03B161F68B40]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 814733 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/control/cores/collection1
   [junit4]   2> 814734 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 814735 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1613c69{/,null,AVAILABLE}
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1aae2d0{HTTP/1.1,[http/1.1]}{127.0.0.1:43936}
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] o.e.j.s.Server 
Started @817055ms
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=43936, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/control/cores}
   [junit4]   2> 814737 ERROR 
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.4.2
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 814737 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-04T14:38:59.891Z
   [junit4]   2> 814738 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/control/solr.xml
   [junit4]   2> 814749 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 814754 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/control/cores
   [junit4]   2> 814754 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 814754 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 814789 WARN  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 814791 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.2
   [junit4]   2> 814822 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 814908 WARN  (coreLoadExecutor-3452-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> 814910 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 814914 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 814915 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 814915 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@86ce0
   [junit4]   2> 814916 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=28, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=98.9326171875, 
floorSegmentMB=0.88671875, forceMergeDeletesPctAllowed=18.823466352676405, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 814934 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 814934 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 814938 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 814938 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 814938 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=82.5009765625, 
floorSegmentMB=1.36328125, forceMergeDeletesPctAllowed=12.153817007240749, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 814938 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5730e4[collection1] 
main]
   [junit4]   2> 814938 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 814984 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 815230 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 815230 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 815230 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 815230 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 815230 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 815231 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 815231 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 815231 INFO  (coreLoadExecutor-3452-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1560950436407541760
   [junit4]   2> 815231 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 815231 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 815231 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 815233 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5730e4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 815702 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 815703 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 815704 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@11c6c42{/,null,AVAILABLE}
   [junit4]   2> 815704 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7347a4{HTTP/1.1,[http/1.1]}{127.0.0.1:37640}
   [junit4]   2> 815704 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] o.e.j.s.Server 
Started @818023ms
   [junit4]   2> 815704 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=37640, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/shard0/cores}
   [junit4]   2> 815704 ERROR 
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 815704 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.4.2
   [junit4]   2> 815705 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 815705 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 815705 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-04T14:39:00.859Z
   [junit4]   2> 815705 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 815709 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 815712 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/shard0/cores
   [junit4]   2> 815712 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 815712 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 815732 WARN  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 815733 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.2
   [junit4]   2> 815760 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 815839 WARN  (coreLoadExecutor-3463-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> 815840 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 815845 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 815845 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_124B03B161F68B40-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 815846 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@86ce0
   [junit4]   2> 815847 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=28, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=98.9326171875, 
floorSegmentMB=0.88671875, forceMergeDeletesPctAllowed=18.823466352676405, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 815864 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 815864 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 815868 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 815868 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 815869 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=82.5009765625, 
floorSegmentMB=1.36328125, forceMergeDeletesPctAllowed=12.153817007240749, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 815870 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11c82b5[collection1] 
main]
   [junit4]   2> 815870 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 815905 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 816095 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 816095 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 816096 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 816096 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 816096 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11c82b5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 816097 INFO  (coreLoadExecutor-3463-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1560950437315608576
   [junit4]   2> 816450 INFO  
(TEST-TestDistributedSearch.test-seed#[124B03B161F68B40]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 816453 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560950437687853056)} 0 1
   [junit4]   2> 816462 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1560950437689950208)} 0 8
   [junit4]   2> 816465 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1560950437700435968)]} 0 1
   [junit4]   2> 816467 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1560950437703581696)]} 0 0
   [junit4]   2> 816469 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1560950437705678848)]} 0 0
   [junit4]   2> 816483 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1560950437707776000)]} 0 12
   [junit4]   2> 816486 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1560950437722456064)]} 0 0
   [junit4]   2> 816488 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1560950437724553216)]} 0 0
   [junit4]   2> 816490 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1560950437727698944)]} 0 0
   [junit4]   2> 816492 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1560950437728747520)]} 0 1
   [junit4]   2> 816494 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1560950437731893248)]} 0 0
   [junit4]   2> 816497 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1560950437733990400)]} 0 1
   [junit4]   2> 816499 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1560950437737136128)]} 0 0
   [junit4]   2> 816502 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1560950437739233280)]} 0 1
   [junit4]   2> 816504 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1560950437742379008)]} 0 0
   [junit4]   2> 816507 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1560950437744476160)]} 0 0
   [junit4]   2> 816509 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1560950437746573312)]} 0 0
   [junit4]   2> 816511 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1560950437748670464)]} 0 0
   [junit4]   2> 816513 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1560950437750767616)]} 0 0
   [junit4]   2> 816514 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1560950437752864768)]} 0 0
   [junit4]   2> 816516 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 816516 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@76aca
   [junit4]   2> 816531 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@d84dab[collection1] main]
   [junit4]   2> 816531 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 816533 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d84dab[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.2):c9)))}
   [junit4]   2> 816534 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 17
   [junit4]   2> 816535 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 816535 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13d9419
   [junit4]   2> 816548 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@ad6347[collection1] main]
   [junit4]   2> 816550 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ad6347[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.2):c9)))}
   [junit4]   2> 816555 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 816555 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 20
   [junit4]   2> 816561 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1560950437797953536)]} 0 3
   [junit4]   2> 816572 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1560950437803196416)]} 0 10
   [junit4]   2> 816575 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1560950437815779328)]} 0 1
   [junit4]   2> 816578 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1560950437818925056)]} 0 0
   [junit4]   2> 816580 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1560950437821022208)]} 0 0
   [junit4]   2> 816582 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1560950437823119360)]} 0 0
   [junit4]   2> 816584 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1560950437825216512)]} 0 0
   [junit4]   2> 816586 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1560950437827313664)]} 0 0
   [junit4]   2> 816590 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1560950437829410816)]} 0 2
   [junit4]   2> 816608 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1560950437850382336)]} 0 16
   [junit4]   2> 816611 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1560950437853528064)]} 0 0
   [junit4]   2> 816614 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1560950437856673792)]} 0 0
   [junit4]   2> 816616 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1560950437859819520)]} 0 0
   [junit4]   2> 816618 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1560950437861916672)]} 0 0
   [junit4]   2> 816620 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1560950437862965248)]} 0 0
   [junit4]   2> 816622 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1560950437865062400)]} 0 1
   [junit4]   2> 816624 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1560950437868208128)]} 0 0
   [junit4]   2> 816628 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1560950437870305280)]} 0 1
   [junit4]   2> 816630 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1560950437873451008)]} 0 0
   [junit4]   2> 816638 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1560950437875548160)]} 0 7
   [junit4]   2> 816641 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1560950437884985344)]} 0 0
   [junit4]   2> 816650 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1560950437887082496)]} 0 8
   [junit4]   2> 816652 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1560950437897568256)]} 0 0
   [junit4]   2> 816654 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1560950437899665408)]} 0 0
   [junit4]   2> 816656 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1560950437901762560)]} 0 0
   [junit4]   2> 816658 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1560950437903859712)]} 0 0
   [junit4]   2> 816659 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1560950437904908288)]} 0 0
   [junit4]   2> 816668 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1560950437907005440)]} 0 6
   [junit4]   2> 816670 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1560950437916442624)]} 0 0
   [junit4]   2> 816674 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1560950437918539776)]} 0 2
   [junit4]   2> 816676 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1560950437921685504)]} 0 0
   [junit4]   2> 816684 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1560950437923782656)]} 0 7
   [junit4]   2> 816687 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1560950437933219840)]} 0 0
   [junit4]   2> 816688 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1560950437935316992)]} 0 0
   [junit4]   2> 816690 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1560950437937414144)]} 0 0
   [junit4]   2> 816692 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1560950437938462720)]} 0 0
   [junit4]   2> 816694 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1560950437940559872)]} 0 1
   [junit4]   2> 816696 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1560950437943705600)]} 0 0
   [junit4]   2> 816698 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1560950437945802752)]} 0 0
   [junit4]   2> 816700 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1560950437947899904)]} 0 0
   [junit4]   2> 816702 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1560950437948948480)]} 0 0
   [junit4]   2> 816704 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1560950437951045632)]} 0 0
   [junit4]   2> 816705 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1560950437953142784)]} 0 0
   [junit4]   2> 816707 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1560950437955239936)]} 0 0
   [junit4]   2> 816710 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1560950437957337088)]} 0 1
   [junit4]   2> 816712 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1560950437960482816)]} 0 0
   [junit4]   2> 816714 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1560950437962579968)]} 0 0
   [junit4]   2> 816716 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1560950437964677120)]} 0 0
   [junit4]   2> 816718 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1560950437966774272)]} 0 0
   [junit4]   2> 816720 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1560950437968871424)]} 0 0
   [junit4]   2> 816722 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1560950437970968576)]} 0 0
   [junit4]   2> 816724 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1560950437973065728)]} 0 0
   [junit4]   2> 816726 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1560950437975162880)]} 0 0
   [junit4]   2> 816728 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1560950437977260032)]} 0 0
   [junit4]   2> 816730 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1560950437979357184)]} 0 0
   [junit4]   2> 816756 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1560950437980405760)]} 0 24
   [junit4]   2> 816768 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1560950438010814464)]} 0 7
   [junit4]   2> 816770 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1560950438021300224)]} 0 0
   [junit4]   2> 816772 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1560950438023397376)]} 0 0
   [junit4]   2> 816774 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1560950438025494528)]} 0 0
   [junit4]   2> 816776 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1560950438027591680)]} 0 0
   [junit4]   2> 816778 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1560950438028640256)]} 0 0
   [junit4]   2> 816779 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1560950438030737408)]} 0 0
   [junit4]   2> 816781 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1560950438032834560)]} 0 0
   [junit4]   2> 816783 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1560950438033883136)]} 0 0
   [junit4]   2> 816785 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1560950438035980288)]} 0 0
   [junit4]   2> 816787 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1560950438039126016)]} 0 0
   [junit4]   2> 816789 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1560950438040174592)]} 0 0
   [junit4]   2> 816790 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1560950438042271744)]} 0 0
   [junit4]   2> 816792 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1560950438044368896)]} 0 0
   [junit4]   2> 816811 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1560950438046466048)]} 0 16
   [junit4]   2> 816813 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1560950438065340416)]} 0 0
   [junit4]   2> 816815 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1560950438067437568)]} 0 0
   [junit4]   2> 816816 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1560950438069534720)]} 0 0
   [junit4]   2> 816818 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1560950438071631872)]} 0 0
   [junit4]   2> 816820 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1560950438073729024)]} 0 0
   [junit4]   2> 816829 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1560950438075826176)]} 0 7
   [junit4]   2> 816831 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1560950438085263360)]} 0 0
   [junit4]   2> 816833 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1560950438086311936)]} 0 0
   [junit4]   2> 816835 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1560950438088409088)]} 0 0
   [junit4]   2> 816836 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1560950438090506240)]} 0 0
   [junit4]   2> 816838 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1560950438092603392)]} 0 0
   [junit4]   2> 816839 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1560950438093651968)]} 0 0
   [junit4]   2> 816841 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1560950438095749120)]} 0 0
   [junit4]   2> 816843 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1560950438097846272)]} 0 0
   [junit4]   2> 816846 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1560950438099943424)]} 0 0
   [junit4]   2> 816848 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1560950438102040576)]} 0 0
   [junit4]   2> 816851 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1560950438104137728)]} 0 1
   [junit4]   2> 816853 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1560950438108332032)]} 0 0
   [junit4]   2> 816855 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1560950438109380608)]} 0 1
   [junit4]   2> 816857 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1560950438112526336)]} 0 0
   [junit4]   2> 816860 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1560950438114623488)]} 0 1
   [junit4]   2> 816863 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1560950438117769216)]} 0 0
   [junit4]   2> 816865 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1560950438119866368)]} 0 0
   [junit4]   2> 816866 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1560950438121963520)]} 0 0
   [junit4]   2> 816875 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1560950438124060672)]} 0 7
   [junit4]   2> 816885 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1560950438133497856)]} 0 7
   [junit4]   2> 816887 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1560950438142935040)]} 0 0
   [junit4]   2> 816889 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1560950438145032192)]} 0 0
   [junit4]   2> 816890 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1560950438147129344)]} 0 0
   [junit4]   2> 816892 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1560950438149226496)]} 0 0
   [junit4]   2> 816894 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1560950438151323648)]} 0 0
   [junit4]   2> 816896 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1560950438153420800)]} 0 0
   [junit4]   2> 816897 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1560950438154469376)]} 0 0
   [junit4]   2> 816899 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1560950438156566528)]} 0 0
   [junit4]   2> 816901 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1560950438157615104)]} 0 0
   [junit4]   2> 816910 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1560950438159712256)]} 0 8
   [junit4]   2> 816912 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1560950438170198016)]} 0 0
   [junit4]   2> 816914 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1560950438172295168)]} 0 0
   [junit4]   2> 816916 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1560950438174392320)]} 0 0
   [junit4]   2> 816918 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1560950438176489472)]} 0 0
   [junit4]   2> 816919 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1560950438177538048)]} 0 0
   [junit4]   2> 816923 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1560950438179635200)]} 0 1
   [junit4]   2> 816925 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1560950438182780928)]} 0 0
   [junit4]   2> 816928 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1560950438184878080)]} 0 1
   [junit4]   2> 816930 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1560950438188023808)]} 0 0
   [junit4]   2> 816931 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1560950438190120960)]} 0 0
   [junit4]   2> 816943 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1560950438192218112)]} 0 10
   [junit4]   2> 816945 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 816945 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@76aca
   [junit4]   2> 816965 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@141eaa7[collection1] main]
   [junit4]   2> 816967 INFO  
(searcherExecutor-3453-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@141eaa7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.2):c9)
 Uninverting(_1(6.4.2):c59)))}
   [junit4]   2> 816967 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 816967 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 22
   [junit4]   2> 816969 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 816969 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@13d9419
   [junit4]   2> 816993 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1f48621[collection1] main]
   [junit4]   2> 816994 INFO  
(searcherExecutor-3464-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1f48621[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.2):c9)
 Uninverting(_1(6.4.2):c59)))}
   [junit4]   2> 817001 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 817001 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 32
   [junit4]   2> 817003 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 817003 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 817005 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 817005 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 817008 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817017 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342165&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817022 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342165&ids=110,111,144,113,146,124,16,139,119,120&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817023 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1|[ff01::114]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 817026 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817030 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:37640//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342182&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817032 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342182&ids=145,2,134,102,103,114,17,106,107,131&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817034 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817036 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817040 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342192&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817043 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342192&ids=133,1,111,103,137,104,16,139,140,142&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817044 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817046 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817050 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342202&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817052 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342202&ids=134,124,135,113,15,148,149,107,109,130&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817053 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 817055 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817060 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:37640//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342212&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817062 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342212&ids=110,147,5,105,149,17,139,108,140,142&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817063 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817065 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817069 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342221&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817072 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342221&ids=122,146,113,103,15,6,106,107,119,9&isShard=true&shard.url=http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817072 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817075 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817084 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342236&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817086 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342236&ids=13,126,16,149,117,139,8,129,108,130&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817087 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:37640//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817089 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817096 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342248&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817098 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342248&ids=132,1,124,113,15,103,114,115,17,138&isShard=true&shard.url=http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817099 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 817101 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817108 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342258&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817118 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342258&ids=100,133,113,136,125,104,5,106,129,140&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817119 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=15
   [junit4]   2> 817121 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817126 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342278&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817128 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342278&ids=144,3,15,16,128,7,107,9,130,10&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817129 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817132 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817136 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342288&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817138 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342288&ids=100,102,114,15,4,103,115,139,109,120&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817138 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:37640//collection1&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 817140 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817144 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342296&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817146 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342296&ids=143,132,1,133,134,13,136,116,138,118&isShard=true&shard.url=http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817146 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 817148 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817152 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342304&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817154 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342304&ids=132,12,101,145,124,114,103,149,7,139&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817154 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 817157 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817161 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:37640//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342313&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817163 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342313&ids=1,134,146,17,107,9,119,140,141,131&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817164 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::114]:33332/&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817168 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817172 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1488638342324&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817174 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342324&ids=121,144,112,146,125,16,116,138,107,120&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817175 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:37640//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817177 INFO  (qtp23161844-6646) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817182 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342334&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817187 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342334&ids=134,3,124,4,103,104,5,148,8,9&isShard=true&shard.url=http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817188 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 817190 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817195 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:37640//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1488638342347&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817197 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342347&ids=11,111,145,14,146,125,126,127,117,130&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817198 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817200 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817204 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:37640//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1488638342356&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817206 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342356&ids=110,112,101,147,114,5,128,9,120,131&isShard=true&shard.url=http://127.0.0.1:37640//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817207 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:37640//collection1|[ff01::213]:33332/&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 817210 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817214 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:37640//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1488638342366&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817216 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342366&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:37640//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817217 INFO  (qtp19742553-6662) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:37640//collection1|[ff01::213]:33332/&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817220 INFO  (qtp23161844-6640) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 817225 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342376&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817227 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342376&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817228 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:37640//collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817230 INFO  (qtp23161844-6645) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 817234 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342386&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817237 INFO  (qtp19742553-6663) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1488638342386&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817238 INFO  (qtp19742553-6664) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:37640//collection1&sort=a_i1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 817240 INFO  (qtp23161844-6647) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 817244 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:37640//collection1&rows=10&version=2&q=*:*&NOW=1488638342396&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 817247 INFO  (qtp19742553-6658) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1488638342396&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:37640//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 817248 INFO  (qtp19742553-6657) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select params={q=*:*&shar

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

r%2Fxa    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> 
(1)
   [junit4]   2> 1873936 INFO  
(zkCallback-2033-thread-1-processing-n:127.0.0.1:46062__r%2Fxa) 
[n:127.0.0.1:46062__r%2Fxa    ] o.a.s.c.Overseer Overseer 
(id=97559469857308689-127.0.0.1:46062__r%2Fxa-n_0000000004) starting
   [junit4]   2> 1873939 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@15bc941{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1873939 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1976f5c{/_r/xa,null,UNAVAILABLE}
   [junit4]   2> 1873940 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.ChaosMonkey monkey: stop shard! 46062
   [junit4]   2> 1873940 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=30012556
   [junit4]   2> 1873943 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.Overseer Overseer 
(id=97559469857308689-127.0.0.1:46062__r%2Fxa-n_0000000004) closing
   [junit4]   2> 1873943 INFO  
(OverseerStateUpdate-97559469857308689-127.0.0.1:46062__r%2Fxa-n_0000000004) 
[n:127.0.0.1:46062__r%2Fxa    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:46062__r%2Fxa
   [junit4]   2> 1874912 WARN  
(zkCallback-2033-thread-2-processing-n:127.0.0.1:46062__r%2Fxa) 
[n:127.0.0.1:46062__r%2Fxa c:collection1 s:shard1 r:core_node4 x:collection1] 
o.a.s.c.SyncStrategy Closed, skipping sync up.
   [junit4]   2> 1874912 INFO  
(zkCallback-2033-thread-2-processing-n:127.0.0.1:46062__r%2Fxa) 
[n:127.0.0.1:46062__r%2Fxa c:collection1 s:shard1 r:core_node4 x:collection1] 
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we 
can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 1874912 INFO  
(zkCallback-2033-thread-2-processing-n:127.0.0.1:46062__r%2Fxa) 
[n:127.0.0.1:46062__r%2Fxa c:collection1 s:shard1 r:core_node4 x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@14e3651
   [junit4]   2> 1874930 INFO  
(zkCallback-2033-thread-2-processing-n:127.0.0.1:46062__r%2Fxa) 
[n:127.0.0.1:46062__r%2Fxa c:collection1 s:shard1 r:core_node4 x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 1875443 WARN  
(zkCallback-2033-thread-1-processing-n:127.0.0.1:46062__r%2Fxa) 
[n:127.0.0.1:46062__r%2Fxa    ] o.a.s.c.c.ZkStateReader ZooKeeper watch 
triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for 
/live_nodes]
   [junit4]   2> 1875444 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1875445 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@1a8770a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1875445 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@145124c{/_r/xa,null,UNAVAILABLE}
   [junit4]   2> 1875446 INFO  
(TEST-ShardSplitTest.testSplitAfterFailedSplit-seed#[124B03B161F68B40]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:37514 37514
   [junit4]   2> 1880468 INFO  (Thread-2689) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:37514 37514
   [junit4]   2> 1880469 WARN  (Thread-2689) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2>        6       /solr/clusterprops.json
   [junit4]   2>        5       /solr/security.json
   [junit4]   2>        5       /solr/configs/conf1
   [junit4]   2>        4       /solr/collections/collection1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        36      /solr/overseer/queue
   [junit4]   2>        23      /solr/overseer/collection-queue-work
   [junit4]   2>        6       /solr/live_nodes
   [junit4]   2>        6       /solr/collections
   [junit4]   2>        6       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=ShardSplitTest 
-Dtests.method=testSplitAfterFailedSplit -Dtests.seed=124B03B161F68B40 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vi 
-Dtests.timezone=Africa/Dakar -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 36.7s J1 | ShardSplitTest.testSplitAfterFailedSplit <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<1> but 
was:<2>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([124B03B161F68B40:EB06901E5D83C6CA]:0)
   [junit4]    >        at 
org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit(ShardSplitTest.java:284)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1880472 INFO  
(SUITE-ShardSplitTest-seed#[124B03B161F68B40]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ShardSplitTest_124B03B161F68B40-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{shard_s=PostingsFormat(name=Asserting), rnd_b=FST50, 
_version_=PostingsFormat(name=Asserting), n_ti=PostingsFormat(name=Asserting), 
a_t=PostingsFormat(name=Asserting), a_i=FST50, id=FST50}, docValues:{}, 
maxPointsInLeafNode=1888, maxMBSortInHeap=5.239806182856572, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=vi, 
timezone=Africa/Dakar
   [junit4]   2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0_121 
(32-bit)/cpus=12,threads=1,free=129867720,total=319012864
   [junit4]   2> NOTE: All tests run in this JVM: [TestLegacyFieldCache, 
TestComplexPhraseQParserPlugin, TestJettySolrRunner, MoreLikeThisHandlerTest, 
TestTolerantUpdateProcessorRandomCloud, ZkSolrClientTest, BlockDirectoryTest, 
TestFastOutputStream, HdfsNNFailoverTest, TestNoOpRegenerator, 
CollectionsAPIDistributedZkTest, SuggesterFSTTest, 
DistribJoinFromCollectionTest, TestCursorMarkWithoutUniqueKey, TestInitQParser, 
TestSuggestSpellingConverter, TestSearchPerf, TestRandomCollapseQParserPlugin, 
WordBreakSolrSpellCheckerTest, WrapperMergePolicyFactoryTest, TestConfigReload, 
ZkStateWriterTest, TestFilteredDocIdSet, SmileWriterTest, 
TestManagedSchemaThreadSafety, ShufflingReplicaListTransformerTest, 
DistributedSuggestComponentTest, DeleteStatusTest, 
TestDelegationWithHadoopAuth, TestCSVResponseWriter, TestFieldTypeResource, 
TestStressUserVersions, TestSubQueryTransformer, SolrIndexSplitterTest, 
DistributedTermsComponentTest, AtomicUpdatesTest, FileUtilsTest, 
TestManagedStopFilterFactory, UpdateRequestProcessorFactoryTest, 
DistributedIntervalFacetingTest, TestCustomStream, MultiTermTest, 
TestElisionMultitermQuery, SolrCLIZkUtilsTest, 
TestLMDirichletSimilarityFactory, TestCharFilters, TestScoreJoinQPScore, 
TestMacroExpander, TestDeleteCollectionOnDownNodes, 
TestNonDefinedSimilarityFactory, DocumentBuilderTest, 
HdfsTlogReplayBufferedWhileIndexingTest, TestTestInjection, TestQueryTypes, 
SystemInfoHandlerTest, CdcrUpdateLogTest, TestQueryUtils, TestXmlQParserPlugin, 
InfoHandlerTest, SoftAutoCommitTest, IndexBasedSpellCheckerTest, 
ShardRoutingCustomTest, TestSolrIndexConfig, TestPKIAuthenticationPlugin, 
FullHLLTest, TestSchemaSimilarityResource, DeleteNodeTest, TestExportWriter, 
TestConfigSetsAPIExclusivity, HdfsLockFactoryTest, TestConfigSetProperties, 
RecoveryAfterSoftCommitTest, HttpSolrCallGetCoreTest, 
TestStressCloudBlindAtomicUpdates, IndexSchemaTest, SortByFunctionTest, 
SolrIndexMetricsTest, OpenCloseCoreStressTest, TestSweetSpotSimilarityFactory, 
TestDefaultSearchFieldResource, AnalyticsQueryTest, TestCoreContainer, 
DistributedFacetPivotWhiteBoxTest, MultiThreadedOCPTest, SSLMigrationTest, 
DirectSolrSpellCheckerTest, BooleanFieldTest, SpellingQueryConverterTest, 
TestUninvertingReader, TestCloudPivotFacet, TestJavabinTupleStreamParser, 
TestQuerySenderListener, SimpleMLTQParserTest, TestBulkSchemaAPI, 
TestFiltering, TestSQLHandlerNonCloud, RankQueryTest, 
TestSimpleTrackingShardHandler, OverseerStatusTest, StressHdfsTest, 
TestSystemIdResolver, TestSimpleQParserPlugin, AliasIntegrationTest, RulesTest, 
TestHashPartitioner, SliceStateTest, CopyFieldTest, BJQParserTest, 
TestFieldCacheReopen, CustomCollectionTest, TestUseDocValuesAsStored, 
BasicDistributedZkTest, SyncSliceTest, OverseerTest, ShardRoutingTest, 
TestRandomFaceting, ZkCLITest, TestDistributedGrouping, ZkControllerTest, 
TestReload, TestRangeQuery, SampleTest, TestConfig, 
TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, 
DirectSolrConnectionTest, TestLuceneMatchVersion, TestXIncludeConfig, 
TestPerFieldSimilarity, TestIBSimilarityFactory, TestFastWriter, 
ScriptEngineTest, PrimUtilsTest, RAMDirectoryFactoryTest, TestSolrJ, 
TestLRUCache, ZkNodePropsTest, UUIDFieldTest, TestRTGBase, CursorPagingTest, 
BaseCdcrDistributedZkTest, CdcrBootstrapTest, 
CollectionsAPIAsyncDistributedZkTest, DistributedQueueTest, 
DistributedVersionInfoTest, LeaderInitiatedRecoveryOnCommitTest, 
LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, ShardSplitTest]
   [junit4] Completed [588/680 (2!)] on J1 in 409.04s, 5 tests, 1 failure <<< 
FAILURES!

[...truncated 54980 lines...]


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