Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22339/
Java: 64bit/jdk-10.0.1 -XX:-UseCompressedOops -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.search.TestRecovery.testExistOldBufferLog

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([76BEA893F049F230:28EEB5C67E8662B9]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertFalse(Assert.java:68)
        at org.junit.Assert.assertFalse(Assert.java:79)
        at 
org.apache.solr.search.TestRecovery.testExistOldBufferLog(TestRecovery.java:1071)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:844)




Build Log:
[...truncated 15074 lines...]
   [junit4] Suite: org.apache.solr.search.TestRecovery
   [junit4]   2> 1786504 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
SecureRandom sanity checks: test.solr.allowed.securerandom=null & 
java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001
   [junit4]   2> 1786504 WARN  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
startTrackingSearchers: numOpens=20 numCloses=20
   [junit4]   2> 1786504 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1786505 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1786505 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
updateLog impl=solr.CdcrUpdateLog
   [junit4]   2> 1786505 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
####initCore
   [junit4]   2> 1786505 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1786517 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1786542 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.s.IndexSchema 
[null] Schema name=test
   [junit4]   2> 1786631 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.s.IndexSchema 
Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1786744 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1786744 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1786757 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1786765 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1786765 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1786767 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1786796 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1786806 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1786875 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1786884 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1786885 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1786885 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1786885 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/]
   [junit4]   2> 1786887 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.35434565704728405]
   [junit4]   2> 1787055 WARN  (coreLoadExecutor-8165-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> 1787099 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1787099 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1787100 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1787100 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1787101 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3504149922354357]
   [junit4]   2> 1787101 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@660b1aa1[collection1] main]
   [junit4]   2> 1787101 WARN  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1787102 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1787102 INFO  (coreLoadExecutor-8165-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1604530430075207680
   [junit4]   2> 1787104 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    ] o.a.s.SolrTestCaseJ4 
####initCore end
   [junit4]   2> 1787108 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testLogReplayWithInPlaceUpdatesAndDeletes
   [junit4]   2> 1787109 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1787110 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d04708[collection1] 
realtime]
   [junit4]   2> 1787111 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] 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> 1787111 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1604530430084644864,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1787111 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@162a26eb 
commitCommandVersion:1604530430084644864
   [junit4]   2> 1787112 INFO  
(searcherExecutor-8166-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@660b1aa1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1787127 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14021e0d[collection1] main]
   [junit4]   2> 1787128 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1787129 INFO  (searcherExecutor-8166-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14021e0d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1787129 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 18
   [junit4]   2> 1787132 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A1 
(1604530430104567808)]} 0 1
   [junit4]   2> 1787132 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A2 
(1604530430106664960)]} 0 0
   [junit4]   2> 1787133 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A2 
(1604530430106664961)]} 0 0
   [junit4]   2> 1787201 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@35c47c5e[collection1] 
realtime]
   [junit4]   2> 1787206 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@414be87d[collection1] 
realtime]
   [junit4]   2> 1787210 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{deleteByQuery=id:A2 
(-1604530430107713536)} 0 77
   [junit4]   2> 1787219 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A3 
(1604530430188453888)]} 0 9
   [junit4]   2> 1787221 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A3 
(1604530430198939648)]} 0 1
   [junit4]   2> 1787243 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@260f513d[collection1] 
realtime]
   [junit4]   2> 1787244 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d04e3c5[collection1] 
realtime]
   [junit4]   2> 1787245 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:102 
(-1604530430199988224)} 0 24
   [junit4]   2> 1787246 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A4 
(1604530430225154048)]} 0 0
   [junit4]   2> 1787246 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A4 
(1604530430226202624)]} 0 0
   [junit4]   2> 1787247 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A4 
(1604530430226202625)]} 0 0
   [junit4]   2> 1787255 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f08d8[collection1] 
realtime]
   [junit4]   2> 1787256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={versions=true&wt=json&indent=true}{deleteByQuery=val_i_dvo:201 
(-1604530430227251200)} 0 9
   [junit4]   2> 1787256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A5 
(1604530430236688384)]} 0 0
   [junit4]   2> 1787257 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A5 
(1604530430237736960)]} 0 0
   [junit4]   2> 1787257 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{add=[A5 
(1604530430237736961)]} 0 0
   [junit4]   2> 1787257 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={versions=true&wt=json&indent=true}{delete=[A5 
(-1604530430237736962)]} 0 0
   [junit4]   2> 1787258 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1787258 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=15&wt=xml} status=0 QTime=0
   [junit4]   2> 1787258 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2011665837
   [junit4]   2> 1787274 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1787274 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@49f7cc88: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@1e0fc107
   [junit4]   2> 1787287 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1787287 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@42c7844f: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1119987b
   [junit4]   2> 1787291 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1787291 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@79f2db77: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@56af2732
   [junit4]   2> 1787327 INFO  (coreCloseExecutor-8171-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7454c6e4
   [junit4]   2> 1787327 INFO  (coreCloseExecutor-8171-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=7454c6e4
   [junit4]   2> 1787327 INFO  (coreCloseExecutor-8171-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@39766027: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6e5726f0
   [junit4]   2> 1787376 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1787391 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1787401 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1787468 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1787579 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1787579 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1787595 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1787610 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1787610 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1787612 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1787629 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1787640 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1787711 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1787719 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1787719 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1787719 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1787719 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/]
   [junit4]   2> 1787722 WARN  (coreLoadExecutor-8181-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> 1787773 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1787773 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1787775 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1787775 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1787776 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.35434565704728405]
   [junit4]   2> 1787778 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7e60981b[collection1] main]
   [junit4]   2> 1787778 WARN  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1787778 INFO  (coreLoadExecutor-8181-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1787781 INFO  
(searcherExecutor-8182-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7e60981b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1787782 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1787786 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=15&wt=xml} status=0 QTime=3
   [junit4]   2> 1787786 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=15&wt=xml} status=0 QTime=0
   [junit4]   2> 1787789 WARN  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000001.1604530430104567808
 refcount=1} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 1788314 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3e68080f[collection1] realtime]
   [junit4]   2> 1788329 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@57e2eecd[collection1] realtime]
   [junit4]   2> 1788357 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5bdb173c[collection1] realtime]
   [junit4]   2> 1788358 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4b37370b[collection1] realtime]
   [junit4]   2> 1788381 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1d3687c8[collection1] realtime]
   [junit4]   2> 1788387 INFO  
(recoveryExecutor-8184-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> 1788387 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@774ecdfa commitCommandVersion:0
   [junit4]   2> 1788702 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3658e270[collection1] main]
   [junit4]   2> 1788702 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1788703 INFO  
(searcherExecutor-8182-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3658e270[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C2/1:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_5(8.0.0):C1:fieldInfosGen=1:dvGen=2)))}
   [junit4]   2> 1788704 INFO  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[A1 
(1604530430104567808), A2 (1604530430106664960), A2 (1604530430106664961), A3 
(1604530430188453888), A3 (1604530430198939648), A4 (1604530430225154048), A4 
(1604530430226202624), A4 (1604530430226202625), A5 (1604530430236688384), A5 
(1604530430237736960), ... (11 adds)],deleteByQuery=id:A2 
(-1604530430107713536),deleteByQuery=val_i_dvo:102 
(-1604530430199988224),deleteByQuery=val_i_dvo:201 
(-1604530430227251200),delete=[A5 (-1604530430237736962)]} 0 914
   [junit4]   2> 1788704 WARN  
(recoveryExecutor-8184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=11 
deletes=1 deleteByQuery=3 errors=0 positionOfStart=0}
   [junit4]   2> 1788704 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=val_i_dvo:202&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1788704 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=2 status=0 QTime=0
   [junit4]   2> 1788707 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A2&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1788707 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A3&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1788707 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A4&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1788707 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A5&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1788708 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=15&wt=xml} status=0 QTime=0
   [junit4]   2> 1788711 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{add=[A10 (1604530431759220736)]} 0 3
   [junit4]   2> 1788711 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=758242449
   [junit4]   2> 1788711 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1788712 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@17aa8da5: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@7444532
   [junit4]   2> 1788717 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1788717 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@55fa1428: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1119987b
   [junit4]   2> 1788721 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1788721 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@41083b2e: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@56af2732
   [junit4]   2> 1788723 INFO  (coreCloseExecutor-8187-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6099b2bc
   [junit4]   2> 1788723 INFO  (coreCloseExecutor-8187-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=6099b2bc
   [junit4]   2> 1788723 INFO  (coreCloseExecutor-8187-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2e634659: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@377a1aab
   [junit4]   2> 1788745 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1788760 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1788769 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1788834 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1788974 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1788974 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1788989 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1788998 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1788998 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1789019 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1789035 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1789044 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1789120 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1789125 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1789126 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1789126 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1789126 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/]
   [junit4]   2> 1789134 WARN  (coreLoadExecutor-8197-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> 1789174 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1789174 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1789176 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1789176 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1789177 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.35434565704728405]
   [junit4]   2> 1789179 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ce8ba5c[collection1] 
main]
   [junit4]   2> 1789180 WARN  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1789180 INFO  (coreLoadExecutor-8197-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1789181 INFO  
(searcherExecutor-8198-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@ce8ba5c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C2/1:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_5(8.0.0):C1:fieldInfosGen=1:dvGen=2)))}
   [junit4]   2> 1789182 WARN  
(recoveryExecutor-8200-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000003.1604530431759220736
 refcount=1} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 1789683 INFO  
(recoveryExecutor-8200-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> 1789683 INFO  
(recoveryExecutor-8200-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@c24e30 commitCommandVersion:0
   [junit4]   2> 1789895 INFO  
(recoveryExecutor-8200-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@20c87668[collection1] main]
   [junit4]   2> 1789895 INFO  
(recoveryExecutor-8200-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1789897 INFO  
(searcherExecutor-8198-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@20c87668[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C2/1:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_5(8.0.0):C1:fieldInfosGen=1:dvGen=2) Uninverting(_7(8.0.0):C1)))}
   [junit4]   2> 1789897 INFO  
(recoveryExecutor-8200-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[A10 
(1604530431759220736)]} 0 714
   [junit4]   2> 1789897 WARN  
(recoveryExecutor-8200-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=1 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 1789897 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1789898 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A2&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1789898 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A3&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1789898 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A4&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1789898 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A5&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1789898 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A10&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1789898 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=650051972
   [junit4]   2> 1789899 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1789899 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6789a0b3: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@3a010224
   [junit4]   2> 1789903 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1789903 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@64410dd8: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1119987b
   [junit4]   2> 1789906 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1789906 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7e41ee0: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@56af2732
   [junit4]   2> 1789907 INFO  (coreCloseExecutor-8203-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@525f39b5
   [junit4]   2> 1789907 INFO  (coreCloseExecutor-8203-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=525f39b5
   [junit4]   2> 1789907 INFO  (coreCloseExecutor-8203-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@674c5250: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7d1aeda9
   [junit4]   2> 1789922 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1789944 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1789953 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1790002 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1790103 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1790103 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1790114 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1790120 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1790121 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1790122 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1790142 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1790149 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1790203 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1790209 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1790209 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1790209 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1790209 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/]
   [junit4]   2> 1790211 WARN  (coreLoadExecutor-8213-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> 1790246 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1790246 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1790247 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1790247 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1790247 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.35434565704728405]
   [junit4]   2> 1790251 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@713e2cdb[collection1] main]
   [junit4]   2> 1790251 WARN  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1790252 INFO  (coreLoadExecutor-8213-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1790252 INFO  
(searcherExecutor-8214-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@713e2cdb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C2/1:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_5(8.0.0):C1:fieldInfosGen=1:dvGen=2) Uninverting(_7(8.0.0):C1)))}
   [junit4]   2> 1790255 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=3 status=0 QTime=0
   [junit4]   2> 1790255 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=val_i_dvo:202&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1790256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A2&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1790256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A3&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1790256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A4&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1790256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A5&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1790256 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:A10&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 1790357 INFO  
(TEST-TestRecovery.testLogReplayWithInPlaceUpdatesAndDeletes-seed#[76BEA893F049F230])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending testLogReplayWithInPlaceUpdatesAndDeletes
   [junit4]   2> 1790358 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testDropBuffered
   [junit4]   2> 1790359 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1790359 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@484875be[collection1] realtime]
   [junit4]   2> 1790359 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
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 0
   [junit4]   2> 1790359 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1604530433490419712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1790359 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7d24a3ab 
commitCommandVersion:1604530433490419712
   [junit4]   2> 1790418 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@a073d14[collection1] main]
   [junit4]   2> 1790419 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1790420 INFO  (searcherExecutor-8214-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a073d14[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1790421 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 61
   [junit4]   2> 1790421 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 1790421 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C1]} 0 0
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (1)]} 0 0
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C3 (2)]} 0 0
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000005.0
 refcount=1}}
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000005.0
 refcount=1}}
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C4 (3)]} 0 0
   [junit4]   2> 1790422 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C5 (4)]} 0 0
   [junit4]   2> 1790423 WARN  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/buffer.tlog.0003380576060044206
 refcount=2} active=true starting pos=0 inSortedOrder=false
   [junit4]   2> 1790923 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1790923 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7d24a3ab commitCommandVersion:0
   [junit4]   2> 1791349 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@39ff2bdf[collection1] main]
   [junit4]   2> 1791349 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1791351 INFO  (searcherExecutor-8214-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@39ff2bdf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):C2)))}
   [junit4]   2> 1791351 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C4 (3), C5 (4)]} 0 927
   [junit4]   2> 1791351 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 1791352 WARN  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 1791352 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
   [junit4]   2> 1791353 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C100 (5)]} 0 0
   [junit4]   2> 1791353 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C101 (6)]} 0 0
   [junit4]   2> 1791353 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000006.5
 refcount=1}}
   [junit4]   2> 1791354 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C103 (7)]} 0 0
   [junit4]   2> 1791354 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C104 (8)]} 0 0
   [junit4]   2> 1791354 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000006.5
 refcount=1}}
   [junit4]   2> 1791354 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000006.5
 refcount=1}}
   [junit4]   2> 1791354 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C105 (9)]} 0 0
   [junit4]   2> 1791354 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C106 (10)]} 0 0
   [junit4]   2> 1791354 WARN  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/buffer.tlog.0003380576991702299
 refcount=2} active=true starting pos=0 inSortedOrder=false
   [junit4]   2> 1791855 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1791855 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7d24a3ab commitCommandVersion:0
   [junit4]   2> 1792039 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4f4e33ca[collection1] main]
   [junit4]   2> 1792039 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1792040 INFO  (searcherExecutor-8214-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4f4e33ca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):C2)
 Uninverting(_9(8.0.0):C4)))}
   [junit4]   2> 1792040 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C105 (9), C106 (10)]} 0 
686
   [junit4]   2> 1792040 INFO  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
   [junit4]   2> 1792042 WARN  (recoveryExecutor-8216-thread-1) [    ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 
deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 1792042 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
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> 1792051 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
   [junit4]   2> 1792051 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=null}
   [junit4]   2> 1792052 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (13)]} 0 0
   [junit4]   2> 1792052 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (14)]} 0 0
   [junit4]   2> 1792052 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000007.13
 refcount=1}}
   [junit4]   2> 1792053 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (11)]} 0 0
   [junit4]   2> 1792053 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (12)]} 0 0
   [junit4]   2> 1792053 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1604530435266707456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1792053 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7d24a3ab 
commitCommandVersion:1604530435266707456
   [junit4]   2> 1792130 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@991f6[collection1] main]
   [junit4]   2> 1792130 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1792132 INFO  (searcherExecutor-8214-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@991f6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(8.0.0):C2)
 Uninverting(_9(8.0.0):C4) Uninverting(_a(8.0.0):C2)))}
   [junit4]   2> 1792132 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 78
   [junit4]   2> 1792132 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
   [junit4]   2> 1792132 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
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> 1792133 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (12)]} 0 0
   [junit4]   2> 1792134 INFO  
(TEST-TestRecovery.testDropBuffered-seed#[76BEA893F049F230]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testDropBuffered
   [junit4]   2> 1792136 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testLogReplayWithReorderedDBQByAsterixAndChildDocs
   [junit4]   2> 1792137 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 1792137 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a157ac3[collection1] 
realtime]
   [junit4]   2> 1792137 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] 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 0
   [junit4]   2> 1792138 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1604530435355836416,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1792138 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7d24a3ab 
commitCommandVersion:1604530435355836416
   [junit4]   2> 1792152 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2165669b[collection1] main]
   [junit4]   2> 1792153 INFO  (searcherExecutor-8214-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2165669b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1792154 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1792155 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 17
   [junit4]   2> 1792156 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_1 
(15)]} 0 0
   [junit4]   2> 1792156 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_2 
(18)]} 0 0
   [junit4]   2> 1792159 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bba6216[collection1] 
realtime]
   [junit4]   2> 1792160 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c395577[collection1] 
realtime]
   [junit4]   2> 1792161 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={update.distrib=FROMLEADER&_version_=-17&wt=json&indent=true}{deleteByQuery=_root_:RDBQ2_1
 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4 (-17)} 0 4
   [junit4]   2> 1792161 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.  
Update=add{_version_=16,id=RDBQ2_3} DBQs=[DBQ{version=17,q=_root_:RDBQ2_1 
_root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4}]
   [junit4]   2> 1792164 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a3e5ac[collection1] 
realtime]
   [junit4]   2> 1792164 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_3 
(16)]} 0 3
   [junit4]   2> 1792165 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ2_4 
(19)]} 0 0
   [junit4]   2> 1792165 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1792165 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1052511199
   [junit4]   2> 1792165 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 1792165 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@15bc0708: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@5484156f
   [junit4]   2> 1792171 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 1792171 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@28b0432c: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1119987b
   [junit4]   2> 1792175 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 1792175 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6b42a4d3: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@56af2732
   [junit4]   2> 1792175 INFO  (coreCloseExecutor-8219-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@8725fc6
   [junit4]   2> 1792175 INFO  (coreCloseExecutor-8219-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=8725fc6
   [junit4]   2> 1792175 INFO  (coreCloseExecutor-8219-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@71db224c: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4bd5df5
   [junit4]   2> 1792188 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1792202 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1792211 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1792312 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1792402 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 1792402 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 1792426 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1792440 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1792440 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1792441 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1792461 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1792468 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1792537 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1792543 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1792543 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@18305e59
   [junit4]   2> 1792543 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1792543 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/]
   [junit4]   2> 1792551 WARN  (coreLoadExecutor-8229-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> 1792579 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1792579 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1792581 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1792581 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1792582 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.35434565704728405]
   [junit4]   2> 1792583 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7b69f2d4[collection1] main]
   [junit4]   2> 1792583 WARN  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1792583 INFO  (coreLoadExecutor-8229-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1792584 INFO  
(searcherExecutor-8230-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7b69f2d4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1792586 WARN  
(recoveryExecutor-8232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001/init-core-data-001/tlog/tlog.0000000000000000009.15
 refcount=1} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 1792587 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQByAsterixAndChildDocs-seed#[76BEA893F049F230])
 [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 1793100 INFO  
(recoveryExecutor-8232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@32e39b1e[collection1] realtime]
   [junit4]   2> 1793107 INFO  
(recoveryExecutor-8232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2ba0dd1d[collection1] realtime]
   [junit4]   2> 1793107 INFO  
(replayUpdatesExecutor-8223-thread-1-processing-x:collection1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.  
Update=add{flags=a,_version_=16,id=RDBQ2_3} 
DBQs=[DBQ{version=17,q=_root_:RDBQ2_1 _root_:RDBQ2_2 id:RDBQ2_3 _root_:RDBQ2_4}]
   [junit4]   2> 1793111 INFO  
(replayUpdatesExecutor-8223-thread-1-processing-x:collection1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4dd8f4e5[collection1] realtime]
   [junit4]   2> 1793608 INFO  
(recoveryExecutor-8232-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> 1793608 INFO  
(recoveryExecutor-8232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@698926a0 commitCommandVersion:0
   [junit4]   2> 1793894 INFO  
(recoveryExecutor-8232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.s.Solr

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

 [junit4]   2> 1828518 WARN  
(recoveryExecutor-8572-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=1 
deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
   [junit4]   2> 1828518 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[76BEA893F049F230])
 [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=4 status=0 QTime=0
   [junit4]   2> 1828519 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQInsertingChildnodes-seed#[76BEA893F049F230])
 [    x:collection1] o.a.s.SolrTestCaseJ4 ###Ending 
testLogReplayWithReorderedDBQInsertingChildnodes
   [junit4]   2> 1828519 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1828519 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=453528435
   [junit4]   2> 1828519 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1828519 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3c101e2e: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@37264f89
   [junit4]   2> 1828523 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1828523 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@35795c70: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1119987b
   [junit4]   2> 1828526 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1828526 INFO  
(SUITE-TestRecovery-seed#[76BEA893F049F230]-worker) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1e74c0e7: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@56af2732
   [junit4]   2> 1828526 INFO  
(coreCloseExecutor-8575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@70193062
   [junit4]   2> 1828526 INFO  
(coreCloseExecutor-8575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=70193062
   [junit4]   2> 1828526 INFO  
(coreCloseExecutor-8575-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@67718959: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3d3c574
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestRecovery_76BEA893F049F230-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7919f155),
 locale=nnh-CM, timezone=America/Indiana/Knox
   [junit4]   2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 10.0.1 
(64-bit)/cpus=8,threads=1,free=309704160,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [BlobRepositoryCloudTest, 
HdfsChaosMonkeySafeLeaderTest, TestExclusionRuleCollectionAccess, 
SecurityConfHandlerTest, CdcrBootstrapTest, TimeRoutedAliasUpdateProcessorTest, 
JSONWriterTest, TestQuerySenderListener, BlockJoinFacetDistribTest, 
TestPullReplica, TestFunctionQuery, FullHLLTest, TestManagedStopFilterFactory, 
TestEmbeddedSolrServerAdminHandler, TestRestManager, 
AtomicUpdateProcessorFactoryTest, LeaderVoteWaitTimeoutTest, TestReload, 
TestSSLRandomization, TestSearcherReuse, TestCloudJSONFacetJoinDomain, 
TestSchemaNameResource, DocValuesMultiTest, TestCloudPseudoReturnFields, 
TestGenericDistributedQueue, TestHalfAndHalfDocValues, 
TestCorePropertiesReload, SliceStateTest, TestObjectReleaseTracker, 
TestSubQueryTransformerDistrib, RequestHandlersTest, 
HdfsCollectionsAPIDistributedZkTest, TestDynamicLoading, 
TestHdfsBackupRestoreCore, SparseHLLTest, TestNumericRangeQuery32, 
ResponseBuilderTest, CdcrRequestHandlerTest, TestImpersonationWithHadoopAuth, 
CleanupOldIndexTest, AutoAddReplicasIntegrationTest, SuggesterTest, 
DataDrivenBlockJoinTest, TestRawTransformer, TestDistribDocBasedVersion, 
OverseerRolesTest, TestRangeQuery, TestSolrCloudWithKerberosAlt, 
ClassificationUpdateProcessorIntegrationTest, TestJsonFacets, 
TestGeoJSONResponseWriter, TestLegacyNumericUtils, 
TestLocalFSCloudBackupRestore, TestUtils, TestSolrCLIRunExample, 
ConvertedLegacyTest, LeaderElectionTest, SolrCoreMetricManagerTest, 
TestClassicSimilarityFactory, SystemLogListenerTest, 
TestComplexPhraseQParserPlugin, DocumentAnalysisRequestHandlerTest, 
TestDistribIDF, TestNumericTerms32, MetricTriggerTest, 
HttpPartitionOnCommitTest, TestCharFilters, TestRandomRequestDistribution, 
NumericFieldsTest, TestSystemCollAutoCreate, SolrTestCaseJ4Test, 
TestDocBasedVersionConstraints, TestDocumentBuilder, SolrJmxReporterCloudTest, 
TestInfoStreamLogging, DistributedSpellCheckComponentTest, 
XmlUpdateRequestHandlerTest, ConjunctionSolrSpellCheckerTest, 
TestConfigOverlay, MoveReplicaHDFSTest, DeleteReplicaTest, TestDocSet, 
SolrCoreCheckLockOnStartupTest, TestUseDocValuesAsStored, 
RestoreTriggerStateTest, TestSQLHandlerNonCloud, TestCollectionAPI, 
DistributedFacetPivotLargeTest, TestChildDocTransformer, 
PreAnalyzedFieldManagedSchemaCloudTest, RequiredFieldsTest, 
ExternalFileFieldSortTest, TestIBSimilarityFactory, MaxSizeAutoCommitTest, 
TestStressInPlaceUpdates, TestSchemaManager, RecoveryZkTest, 
ScheduledTriggerTest, TestSizeLimitedDistributedMap, LIRRollingUpdatesTest, 
UUIDUpdateProcessorFallbackTest, OrderedExecutorTest, 
CoreAdminCreateDiscoverTest, HighlighterConfigTest, 
SuggestComponentContextFilterQueryTest, DistributedQueueTest, 
UnloadDistributedZkTest, AddReplicaTest, DistanceUnitsTest, TestJoin, 
TestPayloadScoreQParserPlugin, GraphQueryTest, RuleEngineTest, 
ChaosMonkeySafeLeaderTest, DistributedFacetPivotSmallTest, TestTrie, 
TestQueryUtils, ShardRoutingCustomTest, NoCacheHeaderTest, ForceLeaderTest, 
CollectionReloadTest, TestFieldCacheVsDocValues, 
NodeLostTriggerIntegrationTest, TestClusterProperties, TestZkChroot, 
CursorMarkTest, TestTriggerIntegration, TestCollectionAPIs, 
HdfsBasicDistributedZk2Test, ZkStateReaderTest, TestManagedSchemaThreadSafety, 
PeerSyncWithIndexFingerprintCachingTest, MetricsHistoryIntegrationTest, 
TestCloudRecovery, TestConfigSetsAPIZkFailure, TestCryptoKeys, 
TestLeaderElectionZkExpiry, TestOnReconnectListenerSupport, TestTlogReplica, 
ComputePlanActionTest, HttpTriggerListenerTest, NodeLostTriggerTest, 
ScheduledMaintenanceTriggerTest, TestPolicyCloud, 
TriggerSetPropertiesIntegrationTest, HdfsRecoverLeaseTest, 
HdfsWriteToMultipleCollectionsTest, RulesTest, TestQuerySenderNoQuery, 
TestShardHandlerFactory, TestSimpleTextCodec, TestSolrDeletionPolicy1, 
TestSolrIndexConfig, TestSolrCoreSnapshots, TestBlobHandler, TestReqParamsAPI, 
V2ApiIntegrationTest, CoreAdminOperationTest, CoreAdminRequestStatusTest, 
InfoHandlerTest, MBeansHandlerTest, DistributedTermsComponentTest, 
TermVectorComponentTest, TermsComponentTest, 
TestDistributedStatsComponentCardinality, SolrJmxReporterTest, 
SolrShardReporterTest, TestUnInvertedFieldException, TestMacroExpander, 
SmileWriterTest, TestCSVResponseWriter, TestGraphMLResponseWriter, 
TestPushWriter, TestCopyFieldCollectionResource, 
TestDynamicFieldCollectionResource, TestFieldCollectionResource, 
TestFieldResource, TestSchemaSimilarityResource, TestSchemaVersionResource, 
TestSerializedLuceneMatchVersion, TestManagedSynonymGraphFilterFactory, 
CurrencyFieldTypeTest, IndexSchemaRuntimeFieldTest, 
ManagedSchemaRoundRobinCloudTest, SchemaVersionSpecificBehaviorTest, 
SynonymTokenizerTest, TestBinaryField, TestBulkSchemaConcurrent, 
TestCloudSchemaless, TestMultiWordSynonyms, TestRTGBase, 
TestReRankQParserPlugin, TestRecovery]
   [junit4] Completed [726/820 (1!)] on J2 in 42.04s, 20 tests, 1 failure <<< 
FAILURES!

[...truncated 44084 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22339/consoleText

[repro] Revision: ab666ff9cfed0d816c58bf64ebf295f7f38f5cd1

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line:  ant test  -Dtestcase=TestRecovery 
-Dtests.method=testExistOldBufferLog -Dtests.seed=76BEA893F049F230 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nnh-CM 
-Dtests.timezone=America/Indiana/Knox -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       TestRecovery
[repro] ant compile-test

[...truncated 3305 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestRecovery" -Dtests.showOutput=onerror 
"-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=76BEA893F049F230 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nnh-CM 
-Dtests.timezone=America/Indiana/Knox -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8

[...truncated 171 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.search.TestRecovery
[repro] Exiting with code 0

[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to