Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6347/
Java: 64bit/jdk1.8.0_112 -XX:+UseCompressedOops -XX:+UseParallelGC

2 tests failed.
FAILED:  org.apache.solr.cloud.RecoveryZkTest.test

Error Message:
Mismatch in counts between replicas

Stack Trace:
java.lang.AssertionError: Mismatch in counts between replicas
        at 
__randomizedtesting.SeedInfo.seed([416C60950450F681:C9385F4FAAAC9B79]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.RecoveryZkTest.assertShardConsistency(RecoveryZkTest.java:143)
        at org.apache.solr.cloud.RecoveryZkTest.test(RecoveryZkTest.java:126)
        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 
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.search.TestRecovery.testBuffering

Error Message:
expected:<6> but was:<10>

Stack Trace:
java.lang.AssertionError: expected:<6> but was:<10>
        at 
__randomizedtesting.SeedInfo.seed([416C60950450F681:5C82CEBEA50957AA]: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.search.TestRecovery.testBuffering(TestRecovery.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 
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 11328 lines...]
   [junit4] Suite: org.apache.solr.search.TestRecovery
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001
   [junit4]   2> 577406 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.SolrTestCaseJ4 
Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 577407 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.SolrTestCaseJ4 
####initCore
   [junit4]   2> 577409 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 577492 WARN  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 577493 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 577504 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.s.IndexSchema 
[null] Schema name=test
   [junit4]   2> 577583 WARN  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.s.IndexSchema 
[null] default search field in schema is text. WARNING: Deprecated, please use 
'df' on request instead.
   [junit4]   2> 577585 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 577614 WARN  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 577618 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 577618 WARN  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 577624 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 577624 WARN  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 577624 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 577628 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 577674 WARN  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 577675 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 577695 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 577765 WARN  (coreLoadExecutor-2559-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> 577766 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 577786 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 577788 WARN  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 577788 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 577788 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\]
   [junit4]   2> 577788 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 577797 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=48, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=76.2060546875, 
floorSegmentMB=2.0361328125, forceMergeDeletesPctAllowed=22.600517322292525, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.30515903624142365
   [junit4]   2> 577828 WARN  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 577848 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 577848 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 577850 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 577850 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 577851 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1642995643, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 577855 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ac4060[collection1] 
main]
   [junit4]   2> 577858 WARN  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 577858 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 577859 INFO  
(searcherExecutor-2560-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4ac4060[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 577860 INFO  (coreLoadExecutor-2559-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1556092455834615808
   [junit4]   2> 577864 INFO  
(SUITE-TestRecovery-seed#[416C60950450F681]-worker) [    ] o.a.s.SolrTestCaseJ4 
####initCore end
   [junit4]   2> 577866 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testRecoveryMultipleLogs
   [junit4]   2> 577867 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 577868 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7453867b[collection1] realtime]
   [junit4]   2> 577868 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 577868 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 577868 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5754cfc6
   [junit4]   2> 577875 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5482a0fc[collection1] main]
   [junit4]   2> 577876 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 577876 INFO  (searcherExecutor-2560-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5482a0fc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 577876 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 7
   [junit4]   2> 577885 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[AAAAAA (1556092455853490176)]} 0 6
   [junit4]   2> 577886 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[BBBBBB (1556092455861878784)]} 0 0
   [junit4]   2> 577887 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[CCCCCC (1556092455862927360)]} 0 0
   [junit4]   2> 577887 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=344491359
   [junit4]   2> 577889 INFO  (coreCloseExecutor-2565-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6273a406
   [junit4]   2> 577912 INFO  (coreCloseExecutor-2565-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 577917 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 577922 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 578016 WARN  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 578018 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 578040 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 578107 WARN  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: 
Deprecated, please use 'df' on request instead.
   [junit4]   2> 578108 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 578126 WARN  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 578128 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578128 WARN  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 578132 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578132 WARN  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 578132 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578135 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 578176 WARN  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 578177 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 578186 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 578272 WARN  (coreLoadExecutor-2572-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> 578274 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 578303 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 578303 WARN  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 578303 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578304 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\]
   [junit4]   2> 578304 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578316 WARN  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 578347 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 578347 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 578349 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 578350 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 578352 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=48, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=76.2060546875, 
floorSegmentMB=2.0361328125, forceMergeDeletesPctAllowed=22.600517322292525, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.30515903624142365
   [junit4]   2> 578355 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@19001f8f[collection1] main]
   [junit4]   2> 578356 WARN  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 578356 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 578357 INFO  
(searcherExecutor-2573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@19001f8f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 578359 INFO  (coreLoadExecutor-2572-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1556092456357855232
   [junit4]   2> 578363 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000001
 refcount=2} active=false starting pos=0
   [junit4]   2> 578365 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Ignoring exception matching OutOfBoundsException
   [junit4]   2> 578365 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 578365 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7090c982
   [junit4]   2> 578436 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@20fe73fb[collection1] main]
   [junit4]   2> 578438 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 578438 INFO  
(searcherExecutor-2573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@20fe73fb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))}
   [junit4]   2> 578438 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[AAAAAA 
(1556092455853490176), BBBBBB (1556092455861878784), CCCCCC 
(1556092455862927360)]} 0 75
   [junit4]   2> 578438 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000002
 refcount=2} active=false starting pos=0
   [junit4]   2> 578441 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Ignoring exception matching OutOfBoundsException
   [junit4]   2> 578441 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 578441 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7090c982
   [junit4]   2> 578524 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@625b458b[collection1] main]
   [junit4]   2> 578526 INFO  
(searcherExecutor-2573-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@625b458b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)
 Uninverting(_1(7.0.0):C3)))}
   [junit4]   2> 578526 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 578526 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[aaaaaa 
(1556092455853490176), bbbbbb (1556092455861878784), cccccc 
(1556092455862927360)]} 0 163
   [junit4]   2> 578527 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=6 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 578527 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=6 status=0 QTime=0
   [junit4]   2> 578528 INFO  
(TEST-TestRecovery.testRecoveryMultipleLogs-seed#[416C60950450F681]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testRecoveryMultipleLogs
   [junit4]   2> 578535 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testReload
   [junit4]   2> 578540 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[reload1 (1556092456542404608)]} 
0 5
   [junit4]   2> 578542 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 578679 WARN  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] o.a.s.c.Config 
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> 
instead.
   [junit4]   2> 578680 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 578698 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 578790 WARN  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.s.IndexSchema [collection1] default search field in schema is text. 
WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 578792 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 578816 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 578824 WARN  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 578834 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578835 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.m.SolrMetricManager Replacing existing reporter 'default' in registry 
'solr.core.collection1': 
[org.apache.solr.metrics.reporters.SolrJmxReporter@44577a9: domain = 
solr.core.collection1, service url = null, agent id = null]
   [junit4]   2> 578837 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\]
   [junit4]   2> 578838 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 578850 WARN  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 578894 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 578894 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 578921 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@269b7b7f[collection1] main]
   [junit4]   2> 578922 WARN  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 578922 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 578925 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@269b7b7f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)
 Uninverting(_1(7.0.0):C3) Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 578976 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=46, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=18.826171875, 
floorSegmentMB=2.17578125, forceMergeDeletesPctAllowed=22.178847916348722, 
segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7234295325441468
   [junit4]   2> 578984 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 579009 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@28c75361[collection1] main]
   [junit4]   2> 579010 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@f8b6d48
   [junit4]   2> 579011 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@28c75361[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)
 Uninverting(_1(7.0.0):C3) Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 579043 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 579046 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[reload1 (1556092457075081216)]} 
0 2
   [junit4]   2> 579046 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579046 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579090 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@9d5b0e[collection1] main]
   [junit4]   2> 579091 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579091 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@9d5b0e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)
 Uninverting(_1(7.0.0):C3) Uninverting(_3(7.0.0):C1)))}
   [junit4]   2> 579095 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 50
   [junit4]   2> 579100 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[reload1 (1556092457131704320)]} 
0 4
   [junit4]   2> 579140 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5d867f51[collection1] realtime]
   [junit4]   2> 579141 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{deleteByQuery=foo_t:hownowbrowncow 
(-1556092457135898624)} 0 40
   [junit4]   2> 579143 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[reload1 (1556092457177841664)]} 
0 2
   [junit4]   2> 579143 INFO  
(TEST-TestRecovery.testReload-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Ending testReload
   [junit4]   2> 579155 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testDropBuffered
   [junit4]   2> 579157 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL 
DOCUMENTS FROM INDEX
   [junit4]   2> 579158 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@48a62c6d[collection1] realtime]
   [junit4]   2> 579168 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 11
   [junit4]   2> 579168 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579168 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579178 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@29eb64b8[collection1] main]
   [junit4]   2> 579178 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579179 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29eb64b8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 579193 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 26
   [junit4]   2> 579193 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 579193 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 579196 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C1 
(101)]} 0 2
   [junit4]   2> 579196 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 
(102)]} 0 0
   [junit4]   2> 579196 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C3 
(103)]} 0 0
   [junit4]   2> 579196 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Dropping buffered updates 
FSUpdateLog{state=BUFFERING, 
tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000007
 refcount=1}}
   [junit4]   2> 579199 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000007
 refcount=1}}
   [junit4]   2> 579199 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C4 
(104)]} 0 0
   [junit4]   2> 579199 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C5 
(105)]} 0 0
   [junit4]   2> 579200 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000007
 refcount=2} active=true starting pos=0
   [junit4]   2> 579208 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579208 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579269 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2fea2961[collection1] main]
   [junit4]   2> 579270 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579271 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2fea2961[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):C2)))}
   [junit4]   2> 579271 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C4 (104), C5 (105)]} 0 
71
   [junit4]   2> 579271 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 579274 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 579275 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
   [junit4]   2> 579278 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C100 
(200)]} 0 3
   [junit4]   2> 579279 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C101 
(201)]} 0 0
   [junit4]   2> 579279 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000008
 refcount=1}}
   [junit4]   2> 579279 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C103 
(203)]} 0 0
   [junit4]   2> 579279 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C104 
(204)]} 0 0
   [junit4]   2> 579279 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Dropping buffered updates 
FSUpdateLog{state=BUFFERING, 
tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000008
 refcount=1}}
   [junit4]   2> 579280 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000008
 refcount=1}}
   [junit4]   2> 579280 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C105 
(205)]} 0 0
   [junit4]   2> 579280 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C106 
(206)]} 0 0
   [junit4]   2> 579280 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000008
 refcount=2} active=true starting pos=92
   [junit4]   2> 579281 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579281 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579319 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5cb78210[collection1] main]
   [junit4]   2> 579319 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579321 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5cb78210[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):C2)
 Uninverting(_7(7.0.0):C4)))}
   [junit4]   2> 579321 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C105 (205), C106 
(206)]} 0 40
   [junit4]   2> 579321 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 579324 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=92}
   [junit4]   2> 579324 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=id,_version_&sort=_version_+asc&wt=xml} hits=6 status=0 QTime=0
   [junit4]   2> 579326 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
   [junit4]   2> 579326 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Starting to buffer updates. 
FSUpdateLog{state=ACTIVE, tlog=null}
   [junit4]   2> 579327 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 
(998)]} 0 1
   [junit4]   2> 579328 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 
(999)]} 0 0
   [junit4]   2> 579328 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.UpdateLog Dropping buffered updates 
FSUpdateLog{state=BUFFERING, 
tlog=tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000009
 refcount=1}}
   [junit4]   2> 579329 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 
(301)]} 0 1
   [junit4]   2> 579330 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 
(302)]} 0 0
   [junit4]   2> 579330 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579330 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579370 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@40ece30d[collection1] main]
   [junit4]   2> 579370 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579372 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@40ece30d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):C2)
 Uninverting(_7(7.0.0):C4) Uninverting(_8(7.0.0):C2)))}
   [junit4]   2> 579372 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 42
   [junit4]   2> 579373 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
   [junit4]   2> 579374 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&fl=id,_version_&sort=_version_+desc&rows=2&wt=xml} hits=8 
status=0 QTime=0
   [junit4]   2> 579377 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 
(302)]} 0 2
   [junit4]   2> 579377 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[416C60950450F681]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testDropBuffered
   [junit4]   2> 579381 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Starting testBuffering
   [junit4]   2> 579383 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 579384 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@42eab37c[collection1] realtime]
   [junit4]   2> 579384 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 579384 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579384 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579391 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@43d4783b[collection1] main]
   [junit4]   2> 579392 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579393 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@43d4783b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 579406 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 21
   [junit4]   2> 579407 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 579407 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 579408 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[B1 (1010)]} 0 1
   [junit4]   2> 579409 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[B11 (1015)]} 0 0
   [junit4]   2> 579409 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&_version_=-1017&wt=json&indent=true}{deleteByQuery=id:B1
 id:B11 id:B2 id:B3 (-1017)} 0 0
   [junit4]   2> 579409 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[B2 (1020)]} 0 0
   [junit4]   2> 579409 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[B3 (1030)]} 0 0
   [junit4]   2> 579409 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&_version_=-2010&versions=true&wt=json&indent=true}{delete=[B1
 (-2010)]} 0 0
   [junit4]   2> 579410 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
   [junit4]   2> 579410 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 579410 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 579411 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
   [junit4]   2> 579411 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 579412 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=B3&wt=xml} status=0 QTime=0
   [junit4]   2> 579412 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\tlog\tlog.0000000000000000011
 refcount=2} active=true starting pos=0
   [junit4]   2> 579434 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@64d90153[collection1] realtime]
   [junit4]   2> 579436 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579436 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579462 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@508877d4[collection1] main]
   [junit4]   2> 579462 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579463 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@508877d4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.0.0):C2)))}
   [junit4]   2> 579465 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[B1 (1010), B11 (1015), 
B2 (1020), B3 (1030)],deleteByQuery=id:B1 id:B11 id:B2 id:B3 (-1017),delete=[B1 
(-2010)]} 0 52
   [junit4]   2> 579465 INFO  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 579469 WARN  
(recoveryExecutor-2575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=4 
deletes=1 deleteByQuery=1 errors=0 positionOfStart=0}
   [junit4]   2> 579470 INFO  
(TEST-TestRecovery.testBuffering-seed#[416C60950450F681]) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###Ending testBuffering
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery 
-Dtests.method=testBuffering -Dtests.seed=416C60950450F681 -Dtests.slow=true 
-Dtests.locale=no -Dtests.timezone=America/Rainy_River -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 0.10s J1 | TestRecovery.testBuffering <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<6> but 
was:<10>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([416C60950450F681:5C82CEBEA50957AA]:0)
   [junit4]    >        at 
org.apache.solr.search.TestRecovery.testBuffering(TestRecovery.java:284)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 579478 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testCleanShutdown
   [junit4]   2> 579480 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL 
DOCUMENTS FROM INDEX
   [junit4]   2> 579481 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3693e53f[collection1] realtime]
   [junit4]   2> 579481 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 579482 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 579482 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579486 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4c29ee61[collection1] main]
   [junit4]   2> 579486 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 579487 INFO  
(searcherExecutor-2578-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4c29ee61[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 579497 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 15
   [junit4]   2> 579510 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[E1 (1556092457553231872)]} 0 12
   [junit4]   2> 579511 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[E2 (1556092457565814784)]} 0 0
   [junit4]   2> 579511 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer 
instance=1349537078
   [junit4]   2> 579513 INFO  
(coreCloseExecutor-2582-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@38a18c96
   [junit4]   2> 579514 INFO  
(coreCloseExecutor-2582-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 579514 INFO  
(coreCloseExecutor-2582-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19ed6a9c
   [junit4]   2> 579803 INFO  
(coreCloseExecutor-2582-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 579812 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 579815 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 579943 WARN  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 579943 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 579968 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 580085 WARN  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.IndexSchema [null] default search field in schema is 
text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 580087 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 580125 WARN  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 580129 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580130 WARN  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 580137 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580137 WARN  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 580137 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580140 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 580179 WARN  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 580181 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 580199 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 580313 WARN  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    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> 580315 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 580347 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 580348 WARN  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 580348 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580348 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\]
   [junit4]   2> 580349 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580368 WARN  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 580404 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 580404 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 580408 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 580408 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 580413 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=48, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=76.2060546875, 
floorSegmentMB=2.0361328125, forceMergeDeletesPctAllowed=22.600517322292525, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.30515903624142365
   [junit4]   2> 580449 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@734df3f8[collection1] main]
   [junit4]   2> 580450 WARN  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 580450 INFO  
(coreLoadExecutor-2589-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 580451 INFO  
(searcherExecutor-2590-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@734df3f8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(7.0.0):C2)))}
   [junit4]   2> 580455 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 580460 INFO  
(TEST-TestRecovery.testCleanShutdown-seed#[416C60950450F681]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCleanShutdown
   [junit4]   2> 580465 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testTruncatedLog
   [junit4]   2> 580467 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL 
DOCUMENTS FROM INDEX
   [junit4]   2> 580474 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1bb60984[collection1] realtime]
   [junit4]   2> 580474 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 7
   [junit4]   2> 580475 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 580475 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5fada6db
   [junit4]   2> 580496 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f84af6[collection1] 
main]
   [junit4]   2> 580498 INFO  
(searcherExecutor-2590-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5f84af6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 580499 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 580524 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 49
   [junit4]   2> 580527 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[F1 (1556092458629070848)]} 0 2
   [junit4]   2> 580528 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[F2 (1556092458632216576)]} 0 0
   [junit4]   2> 580529 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[F3 (1556092458633265152)]} 0 0
   [junit4]   2> 580529 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer 
instance=788528121
   [junit4]   2> 580530 INFO  
(coreCloseExecutor-2595-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3b1157c5
   [junit4]   2> 580574 INFO  
(coreCloseExecutor-2595-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 580593 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 580599 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 580741 WARN  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 580741 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 580775 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 580923 WARN  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.IndexSchema [null] default search field in schema is 
text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 580925 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 580958 WARN  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 580962 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580962 WARN  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 580968 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580968 WARN  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was 
configured, using first MBeanServer.
   [junit4]   2> 580968 INFO  
(TEST-TestRecovery.testTruncatedLog-seed#[416C60950450F681]) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 580976 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 581023 WARN  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 581029 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 581123 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 581207 WARN  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    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> 581209 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 581230 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1
   [junit4]   2> 581230 WARN  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first 
MBeanServer.
   [junit4]   2> 581230 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 581230 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestRecovery_416C60950450F681-001\init-core-data-001\]
   [junit4]   2> 581231 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX 
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5e70153c
   [junit4]   2> 581253 WARN  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = 
requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 581280 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 581280 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 581288 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 581288 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 581290 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=48, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=76.2060546875, 
floorSegmentMB=2.0361328125, forceMergeDeletesPctAllowed=22.600517322292525, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.30515903624142365
   [junit4]   2> 581295 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@63f69d0a[collection1] main]
   [junit4]   2> 581296 WARN  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Cannot write to config directory 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf;
 switching to use InMemory storage instead.
   [junit4]   2> 581296 INFO  
(coreLoadExecutor-2602-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 581297 INFO  
(searcherExecutor-2603-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@63f69d0a[collection1] main{Exit

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

junit4]   2> 1493388 INFO  (jetty-launcher-2211-thread-2) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=716222020
   [junit4]   2> 1493388 INFO  (jetty-launcher-2211-thread-1) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@714970b8{SSL,[ssl, 
http/1.1]}{127.0.0.1:59854}
   [junit4]   2> 1493389 INFO  (jetty-launcher-2211-thread-1) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=343121928
   [junit4]   2> 1493392 INFO  (coreCloseExecutor-5947-thread-1) 
[n:127.0.0.1:59854_solr c:recoverytest s:shard1 r:core_node2 
x:recoverytest_shard1_replica2] o.a.s.c.SolrCore [recoverytest_shard1_replica2] 
 CLOSING SolrCore org.apache.solr.core.SolrCore@3780e6a0
   [junit4]   2> 1493393 INFO  (coreCloseExecutor-5948-thread-1) 
[n:127.0.0.1:59853_solr c:recoverytest s:shard1 r:core_node1 
x:recoverytest_shard1_replica1] o.a.s.c.SolrCore [recoverytest_shard1_replica1] 
 CLOSING SolrCore org.apache.solr.core.SolrCore@7d906f1a
   [junit4]   2> 1493398 INFO  (coreCloseExecutor-5948-thread-1) 
[n:127.0.0.1:59853_solr c:recoverytest s:shard1 r:core_node1 
x:recoverytest_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.core.recoverytest.shard1.replica1
   [junit4]   2> 1493398 INFO  (coreCloseExecutor-5947-thread-1) 
[n:127.0.0.1:59854_solr c:recoverytest s:shard1 r:core_node2 
x:recoverytest_shard1_replica2] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.core.recoverytest.shard1.replica2
   [junit4]   2> 1493400 ERROR 
(recoveryExecutor-2231-thread-1-processing-n:127.0.0.1:59854_solr 
x:recoverytest_shard1_replica2 s:shard1 c:recoverytest r:core_node2) 
[n:127.0.0.1:59854_solr c:recoverytest s:shard1 r:core_node2 
x:recoverytest_shard1_replica2] o.a.s.c.RecoveryStrategy SolrCore not found - 
cannot recover:recoverytest_shard1_replica2
   [junit4]   2> 1493404 WARN  
(zkCallback-2233-thread-1-processing-n:127.0.0.1:59854_solr) 
[n:127.0.0.1:59854_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1493404 INFO  (jetty-launcher-2211-thread-2) [    ] 
o.a.s.c.Overseer Overseer 
(id=97255837613031430-127.0.0.1:59853_solr-n_0000000001) closing
   [junit4]   2> 1493405 INFO  
(OverseerStateUpdate-97255837613031430-127.0.0.1:59853_solr-n_0000000001) 
[n:127.0.0.1:59853_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:59853_solr
   [junit4]   2> 1493405 INFO  
(zkCallback-2221-thread-2-processing-n:127.0.0.1:59853_solr) 
[n:127.0.0.1:59853_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 1493405 INFO  (jetty-launcher-2211-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1493408 INFO  (jetty-launcher-2211-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@bb60bb8{/solr,null,UNAVAILABLE}
   [junit4]   2> 1493409 WARN  
(zkCallback-2221-thread-2-processing-n:127.0.0.1:59853_solr) 
[n:127.0.0.1:59853_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, 
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 1493409 INFO  (jetty-launcher-2211-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1493410 INFO  (jetty-launcher-2211-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@25063162{/solr,null,UNAVAILABLE}
   [junit4]   2> 1493412 INFO  
(SUITE-RecoveryZkTest-seed#[416C60950450F681]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:59849 59849
   [junit4]   2> 1493413 INFO  (Thread-3082) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:59849 59849
   [junit4]   2> 1493414 WARN  (Thread-3082) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        3       /solr/security.json
   [junit4]   2>        3       /solr/configs/conf
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        4       /solr/collections/recoverytest/state.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2>        2       
/solr/collections/recoverytest/leader_elect/shard1/election/97255837613031430-core_node1-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        24      /solr/overseer/queue
   [junit4]   2>        10      /solr/overseer/collection-queue-work
   [junit4]   2>        4       /solr/collections
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2>        3       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 1493414 INFO  
(SUITE-RecoveryZkTest-seed#[416C60950450F681]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.RecoveryZkTest_416C60950450F681-001
   [junit4]   2> Jan 09, 2017 11:58:44 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 4 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{rnd_b=FST50, _version_=Lucene50(blocksize=128), a_t=Lucene50(blocksize=128), 
a_i=FST50, id=FST50}, docValues:{}, maxPointsInLeafNode=1096, 
maxMBSortInHeap=5.142208102878279, sim=RandomSimilarity(queryNorm=true): {}, 
locale=da, timezone=EAT
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_112 
(64-bit)/cpus=3,threads=1,free=186175880,total=515899392
   [junit4]   2> NOTE: All tests run in this JVM: [JsonLoaderTest, 
ReplicationFactorTest, TestLMDirichletSimilarityFactory, CloudMLTQParserTest, 
QueryParsingTest, TestStandardQParsers, TestLocalFSCloudBackupRestore, 
TestMiniSolrCloudClusterSSL, TestChildDocTransformer, CollectionsAPISolrJTest, 
SaslZkACLProviderTest, TestConfig, PreAnalyzedFieldTest, 
TestAddFieldRealTimeGet, UUIDUpdateProcessorFallbackTest, 
TestNonDefinedSimilarityFactory, ConfigSetsAPITest, TestConfigSetsAPIZkFailure, 
StressHdfsTest, TestRealTimeGet, SolrIndexMetricsTest, 
DistributedFacetPivotWhiteBoxTest, DistributedTermsComponentTest, 
TestFreeTextSuggestions, BooleanFieldTest, 
ClassificationUpdateProcessorIntegrationTest, TestRandomFaceting, 
AnalysisErrorHandlingTest, DistanceFunctionTest, TestCorePropertiesReload, 
CoreAdminHandlerTest, FileBasedSpellCheckerTest, DebugComponentTest, 
TestGraphMLResponseWriter, TestPHPSerializedResponseWriter, 
HdfsUnloadDistributedZkTest, SuggesterTest, TestSmileRequest, 
CSVRequestHandlerTest, TestSolrDynamicMBean, DataDrivenBlockJoinTest, 
DistribCursorPagingTest, TestFieldTypeResource, 
DistributedQueryComponentOptimizationTest, TestJoin, 
SharedFSAutoReplicaFailoverTest, TestReversedWildcardFilterFactory, 
CreateCollectionCleanupTest, UtilsToolTest, TestSchemaVersionResource, 
TestFuzzyAnalyzedSuggestions, TestComplexPhraseQParserPlugin, 
OverriddenZkACLAndCredentialsProvidersTest, SimpleCollectionCreateDeleteTest, 
HdfsCollectionsAPIDistributedZkTest, TestSolrCoreParser, 
TestSha256AuthenticationProvider, TestSearcherReuse, ChaosMonkeySafeLeaderTest, 
ShufflingReplicaListTransformerTest, TestLuceneMatchVersion, 
ExitableDirectoryReaderTest, ExplicitHLLTest, ScriptEngineTest, 
CurrencyFieldOpenExchangeTest, TestSolrCloudSnapshots, TestUtils, 
BigEndianAscendingWordSerializerTest, HdfsRecoverLeaseTest, 
TestValueSourceCache, RAMDirectoryFactoryTest, BlockJoinFacetRandomTest, 
TestSystemIdResolver, TestDistribIDF, SolrCoreMetricManagerTest, TestOrdValues, 
TestFoldingMultitermQuery, NoCacheHeaderTest, 
TestTolerantUpdateProcessorRandomCloud, AutoCommitTest, TestConfigOverlay, 
TestCodecSupport, ConnectionReuseTest, CollectionTooManyReplicasTest, 
TestJmxMonitoredMap, TestPKIAuthenticationPlugin, ConvertedLegacyTest, 
TestFieldResource, CoreSorterTest, TestObjectReleaseTracker, TestSearchPerf, 
PreAnalyzedUpdateProcessorTest, JavabinLoaderTest, CdcrBootstrapTest, 
SuggesterTSTTest, TestManagedSchemaAPI, TestShortCircuitedRequests, 
TestLeaderElectionZkExpiry, OverseerModifyCollectionTest, TestRecovery, 
BJQParserTest, TestMacroExpander, BasicAuthIntegrationTest, 
TestDistributedGrouping, TestSolrCoreProperties, ShardRoutingCustomTest, 
MultiThreadedOCPTest, UpdateParamsTest, TestQueryTypes, 
TestMaxScoreQueryParser, TestSolr4Spatial, SpellPossibilityIteratorTest, 
DeleteShardTest, TestSuggestSpellingConverter, CdcrReplicationHandlerTest, 
TestExactStatsCache, PKIAuthenticationIntegrationTest, TestDynamicLoading, 
SuggestComponentContextFilterQueryTest, TestInfoStreamLogging, 
TestMiniSolrCloudCluster, TestRequestForwarding, BasicDistributedZk2Test, 
DistributedVersionInfoTest, LeaderElectionContextKeyTest, TestJsonFacets, 
SpellCheckComponentTest, EchoParamsTest, TestAtomicUpdateErrorCases, 
HLLSerializationTest, DirectUpdateHandlerOptimizeTest, ReturnFieldsTest, 
TestManagedSynonymFilterFactory, TestNamedUpdateProcessors, TestRestManager, 
SolrInfoMBeanTest, AliasIntegrationTest, SpellCheckCollatorTest, 
TestShardHandlerFactory, TestZkChroot, EnumFieldTest, TestRebalanceLeaders, 
CacheHeaderTest, ExternalFileFieldSortTest, TestIndexingPerformance, 
SignatureUpdateProcessorFactoryTest, HdfsBasicDistributedZk2Test, 
TestQueryWrapperFilter, UUIDFieldTest, AnalyticsMergeStrategyTest, 
TestConfigReload, PreAnalyzedFieldManagedSchemaCloudTest, 
TestFieldCacheSanityChecker, TestSolrCloudWithSecureImpersonation, 
LeaderElectionIntegrationTest, TestDocSet, TestDFRSimilarityFactory, 
TestQuerySenderListener, TestRandomFlRTGCloud, TestDeleteCollectionOnDownNodes, 
BasicFunctionalityTest, UnloadDistributedZkTest, 
CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, 
BasicZkTest, RecoveryZkTest]
   [junit4] Completed [314/677 (2!)] on J1 in 13.96s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 63554 lines...]

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

Reply via email to