[JENKINS] Lucene-Solr-Tests-8.3 - Build # 100 - Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.3/100/

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

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
        at __randomizedtesting.SeedInfo.seed([542BC50B5B8CBA27]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.TestRecovery

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
        at __randomizedtesting.SeedInfo.seed([542BC50B5B8CBA27]:0)




Build Log:
[...truncated 16458 lines...]
   [junit4] Suite: org.apache.solr.search.TestRecovery
   [junit4]   2> 4089034 INFO  
(SUITE-TestRecovery-seed#[542BC50B5B8CBA27]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 4089035 INFO  
(SUITE-TestRecovery-seed#[542BC50B5B8CBA27]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-272-001
   [junit4]   2> 4089035 WARN  
(SUITE-TestRecovery-seed#[542BC50B5B8CBA27]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
   [junit4]   2> 4089035 INFO  
(SUITE-TestRecovery-seed#[542BC50B5B8CBA27]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 4089037 INFO  
(SUITE-TestRecovery-seed#[542BC50B5B8CBA27]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 4089124 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting 
testLogReplayWithReorderedDBQUpdateWithDifferentChildCount
   [junit4]   2> 4089125 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
   [junit4]   2> 4089125 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4089125 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4089344 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4089454 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4089983 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4090367 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 4090450 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@6b84ab18[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4090450 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@6b84ab18[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4090494 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4317541a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4090494 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@4317541a[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4090596 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4090596 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 4090703 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4090740 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4090740 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4090741 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4090856 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4090915 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4091557 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field 
id
   [junit4]   2> 4091575 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4091575 INFO  (coreLoadExecutor-12518-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@7c4a0b82
   [junit4]   2> 4091576 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-272-001/]
   [junit4]   2> 4091590 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.3154296875, 
floorSegmentMB=1.291015625, forceMergeDeletesPctAllowed=2.914967029112312, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5272999257306966, deletesPctAllowed=43.33447023651104
   [junit4]   2> 4091627 WARN  (coreLoadExecutor-12518-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> 4091763 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4091763 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4091780 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4091780 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4091781 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=962430735, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 4091782 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3de0a382[collection1] main]
   [junit4]   2> 4091782 WARN  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4091782 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4091782 INFO  (coreLoadExecutor-12518-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1650606210272985088
   [junit4]   2> 4091799 INFO  
(searcherExecutor-12519-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3de0a382[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4091799 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4091801 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM 
INDEX
   [junit4]   2> 4091802 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2eec2ff6[collection1] 
realtime]
   [junit4]   2> 4091803 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] 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> 4091803 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1650606210295005184,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4091803 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6584d8b1 
commitCommandVersion:1650606210295005184
   [junit4]   2> 4091866 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56335317[collection1] main]
   [junit4]   2> 4091866 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4091868 INFO  (searcherExecutor-12519-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@56335317[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4091869 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={}{commit=} 0 65
   [junit4]   2> 4091889 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ5_1]} 
0 17
   [junit4]   2> 4091890 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ5_1 
(1)]} 0 0
   [junit4]   2> 4091890 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ5_2 
(2)]} 0 0
   [junit4]   2> 4091931 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cbf87a7[collection1] 
realtime]
   [junit4]   2> 4091932 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null 
params={update.distrib=FROMLEADER&_version_=-4&wt=json&indent=true}{deleteByQuery=id:RDBQ5_3
 (-4)} 0 42
   [junit4]   2> 4091932 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.  
Update=add{_version_=3,id=RDBQ5_2} DBQs=[DBQ{version=4,q=id:RDBQ5_3}]
   [junit4]   2> 4091967 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3205a490[collection1] 
realtime]
   [junit4]   2> 4091967 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null 
path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[RDBQ5_2 
(3)]} 0 35
   [junit4]   2> 4091968 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 4091968 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=557076922
   [junit4]   2> 4091968 INFO  (coreCloseExecutor-12524-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@73118ed6
   [junit4]   2> 4091968 INFO  (coreCloseExecutor-12524-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@73118ed6
   [junit4]   2> 4091968 INFO  (coreCloseExecutor-12524-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3a726b36: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@42129afc
   [junit4]   2> 4092052 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 4092052 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@22336d25: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@77bc700b
   [junit4]   2> 4092057 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 4092057 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@741e62c9: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3d508666
   [junit4]   2> 4092060 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 4092060 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@d51dd3f: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7810de25
   [junit4]   2> 4092110 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from 
paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4092207 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4092284 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4092807 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4094102 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 4094103 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4bf4fce1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4094103 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@4bf4fce1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4094106 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@1157d36f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4094106 WARN  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@1157d36f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4094180 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 4094180 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics 
history in memory.
   [junit4]   2> 4094247 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4094295 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4094296 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4094297 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4094383 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4094483 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4094710 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field 
id
   [junit4]   2> 4094715 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4094715 INFO  (coreLoadExecutor-12534-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@7c4a0b82
   [junit4]   2> 4094715 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-272-001/]
   [junit4]   2> 4094771 WARN  (coreLoadExecutor-12534-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> 4094892 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4094892 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4094894 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4094894 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4094895 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.3154296875, 
floorSegmentMB=1.291015625, forceMergeDeletesPctAllowed=2.914967029112312, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5272999257306966, deletesPctAllowed=43.33447023651104
   [junit4]   2> 4094897 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@50e08e19[collection1] main]
   [junit4]   2> 4094897 WARN  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4094897 INFO  (coreLoadExecutor-12534-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4094900 INFO  
(searcherExecutor-12535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@50e08e19[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4094900 WARN  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-272-001/tlog/tlog.0000000000000000001
 refcount=2} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 4094921 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 4094991 INFO  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3a540eed[collection1] realtime]
   [junit4]   2> 4094992 INFO  
(replayUpdatesExecutor-12528-thread-1-processing-x:collection1) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.  
Update=add{flags=a,_version_=3,id=RDBQ5_2} DBQs=[DBQ{version=4,q=id:RDBQ5_3}]
   [junit4]   2> 4095014 INFO  
(replayUpdatesExecutor-12528-thread-1-processing-x:collection1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@25b9d0d2[collection1] realtime]
   [junit4]   2> 4095270 INFO  
(recoveryExecutor-12537-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> 4095270 INFO  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3eac2a77 commitCommandVersion:0
   [junit4]   2> 4095602 INFO  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3106f99b[collection1] main]
   [junit4]   2> 4095602 INFO  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4095604 INFO  
(searcherExecutor-12535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3106f99b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.3.1):C10/6:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.3.1, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140752430}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=2)
 Uninverting(_3(8.3.1):C4:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140752467}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 4095604 INFO  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[RDBQ5_1, RDBQ5_1 (1), 
RDBQ5_2 (2), RDBQ5_2 (3)],deleteByQuery=id:RDBQ5_3 (-4)} 0 704
   [junit4]   2> 4095604 WARN  
(recoveryExecutor-12537-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=4 
deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
   [junit4]   2> 4095605 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=*:*&wt=xml} hits=8 status=0 QTime=0
   [junit4]   2> 4095605 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4095605 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1059193175
   [junit4]   2> 4095606 INFO  (coreCloseExecutor-12540-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@76d43b24
   [junit4]   2> 4095606 INFO  (coreCloseExecutor-12540-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@76d43b24
   [junit4]   2> 4095606 INFO  (coreCloseExecutor-12540-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1409637b: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2b289177
   [junit4]   2> 4095780 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.node, tag=null
   [junit4]   2> 4095780 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@31a15583: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@7e1b7633
   [junit4]   2> 4095784 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jvm, tag=null
   [junit4]   2> 4095784 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@27a604a6: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3d508666
   [junit4]   2> 4095787 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.jetty, tag=null
   [junit4]   2> 4095787 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@526d0a4e: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7810de25
   [junit4]   2> 4095805 INFO  
(TEST-TestRecovery.testLogReplayWithReorderedDBQUpdateWithDifferentChildCount-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 ###Ending 
testLogReplayWithReorderedDBQUpdateWithDifferentChildCount
   [junit4]   2> 4095808 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testLogReplay
   [junit4]   2> 4095808 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
   [junit4]   2> 4095808 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 4095809 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4095856 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4095888 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001
   [junit4]   2> 4095891 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4096107 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4096943 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 4096956 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@640783c6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4096956 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@640783c6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4096973 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@56752d20[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4096973 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@56752d20[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4097049 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4097049 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4097109 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4097141 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4097141 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4097143 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4097240 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4097290 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4097652 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field 
id
   [junit4]   2> 4097671 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4097671 INFO  (coreLoadExecutor-12550-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@7c4a0b82
   [junit4]   2> 4097686 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001/]
   [junit4]   2> 4097705 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.3154296875, 
floorSegmentMB=1.291015625, forceMergeDeletesPctAllowed=2.914967029112312, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5272999257306966, deletesPctAllowed=43.33447023651104
   [junit4]   2> 4097722 WARN  (coreLoadExecutor-12550-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> 4097853 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4097853 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4097854 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4097854 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4097868 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=962430735, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 4097868 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@29e3c148[collection1] main]
   [junit4]   2> 4097869 WARN  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4097869 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4097869 INFO  (coreLoadExecutor-12550-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1650606216655667200
   [junit4]   2> 4097888 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 4097889 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 4097904 INFO  
(searcherExecutor-12551-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@29e3c148[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4097933 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7fc00f93[collection1] realtime]
   [junit4]   2> 4097934 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
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 44
   [junit4]   2> 4097934 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1650606216723824640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 4097934 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1763d8d1 
commitCommandVersion:1650606216723824640
   [junit4]   2> 4097998 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@636a5ff5[collection1] main]
   [junit4]   2> 4097999 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4098000 INFO  (searcherExecutor-12551-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@636a5ff5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4098001 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 66
   [junit4]   2> 4098020 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[A1 (1650606216794079232)]} 0 18
   [junit4]   2> 4098020 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[A11 (1650606216814002176)]} 0 0
   [junit4]   2> 4098020 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[A12 (1650606216814002177)]} 0 0
   [junit4]   2> 4098086 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@70317b1e[collection1] realtime]
   [junit4]   2> 4098117 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@625f2adc[collection1] realtime]
   [junit4]   2> 4098117 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{deleteByQuery=id:A11 
(-1650606216814002178)} 0 96
   [junit4]   2> 4098118 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[A13 (1650606216915714048)]} 0 0
   [junit4]   2> 4098118 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[A12 (1650606216916762624)]} 0 0
   [junit4]   2> 4098119 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[A12 (1650606216917811200)]} 0 0
   [junit4]   2> 4098119 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 4098132 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=7&wt=xml} status=0 QTime=12
   [junit4]   2> 4098132 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=730508625
   [junit4]   2> 4098132 INFO  (coreCloseExecutor-12556-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4283ef82
   [junit4]   2> 4098132 INFO  (coreCloseExecutor-12556-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@4283ef82
   [junit4]   2> 4098132 INFO  (coreCloseExecutor-12556-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@56002792: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5d38cc85
   [junit4]   2> 4098237 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4098238 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@231a2cfd: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@20cae18d
   [junit4]   2> 4098244 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4098244 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@ddfa25f: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3d508666
   [junit4]   2> 4098264 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4098264 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5c78e916: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7810de25
   [junit4]   2> 4098291 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4098439 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4098502 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4098879 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4099609 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 4099610 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@8871d8b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4099610 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@8871d8b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4099640 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@77bc6ede[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4099640 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@77bc6ede[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4099709 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4099709 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4099760 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4099796 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4099796 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4099798 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4099898 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4099924 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4100169 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field 
id
   [junit4]   2> 4100187 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4100187 INFO  (coreLoadExecutor-12566-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@7c4a0b82
   [junit4]   2> 4100187 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001/]
   [junit4]   2> 4100202 WARN  (coreLoadExecutor-12566-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> 4100325 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4100325 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4100326 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4100326 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4100327 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.3154296875, 
floorSegmentMB=1.291015625, forceMergeDeletesPctAllowed=2.914967029112312, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5272999257306966, deletesPctAllowed=43.33447023651104
   [junit4]   2> 4100345 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@275fa2c8[collection1] main]
   [junit4]   2> 4100345 WARN  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4100346 INFO  (coreLoadExecutor-12566-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4100348 INFO  
(searcherExecutor-12567-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@275fa2c8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 4100360 WARN  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001/tlog/tlog.0000000000000000001
 refcount=2} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 4100361 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 4100362 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=7&wt=xml} status=0 QTime=0
   [junit4]   2> 4100363 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=7&wt=xml} status=0 QTime=0
   [junit4]   2> 4100627 INFO  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@72d7af70[collection1] realtime]
   [junit4]   2> 4100628 INFO  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6d630ae6[collection1] realtime]
   [junit4]   2> 4100914 INFO  
(recoveryExecutor-12569-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> 4100914 INFO  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6c5ea4d5 commitCommandVersion:0
   [junit4]   2> 4101083 INFO  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3b23535c[collection1] main]
   [junit4]   2> 4101083 INFO  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4101085 INFO  
(searcherExecutor-12567-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3b23535c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.1):C3/1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.3.1, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758079}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_2(8.3.1):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758372}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 4101086 INFO  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[A1 
(1650606216794079232), A11 (1650606216814002176), A12 (1650606216814002177), 
A13 (1650606216915714048), A12 (1650606216916762624), A12 
(1650606216917811200)],deleteByQuery=id:A11 (-1650606216814002178)} 0 725
   [junit4]   2> 4101086 WARN  
(recoveryExecutor-12569-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=6 
deletes=0 deleteByQuery=1 errors=0 positionOfStart=0}
   [junit4]   2> 4101086 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=val_i_dvo:2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4101086 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=3 status=0 QTime=0
   [junit4]   2> 4101087 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&getVersions=7&wt=xml} status=0 QTime=0
   [junit4]   2> 4101088 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[A2 (1650606220029984768)]} 0 1
   [junit4]   2> 4101089 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[A3 (1650606220032081920)]} 0 0
   [junit4]   2> 4101089 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{delete=[A2 (-1650606220032081921)]} 0 0
   [junit4]   2> 4101089 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[A4 (1650606220032081922)]} 0 0
   [junit4]   2> 4101089 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=3 status=0 QTime=0
   [junit4]   2> 4101090 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=val_i_dvo:2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4101090 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=913313633
   [junit4]   2> 4101090 INFO  (coreCloseExecutor-12572-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4b1f0382
   [junit4]   2> 4101090 INFO  (coreCloseExecutor-12572-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@4b1f0382
   [junit4]   2> 4101090 INFO  (coreCloseExecutor-12572-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2da3bf25: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@3f233c7
   [junit4]   2> 4101155 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4101155 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4d7c44dc: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@f4bf268
   [junit4]   2> 4101159 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4101159 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6866f6ab: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3d508666
   [junit4]   2> 4101187 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4101187 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7fb241b7: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7810de25
   [junit4]   2> 4101196 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4101311 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4101429 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4101723 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4102087 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 4102088 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@6ef19ffd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4102088 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@6ef19ffd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4102107 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@6dd243a1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4102107 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@6dd243a1[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4102164 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4102164 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4102211 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4102243 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4102243 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4102244 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4102372 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4102456 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4102788 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field 
id
   [junit4]   2> 4102823 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4102824 INFO  (coreLoadExecutor-12582-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@7c4a0b82
   [junit4]   2> 4102824 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001/]
   [junit4]   2> 4102868 WARN  (coreLoadExecutor-12582-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> 4103079 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4103079 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4103080 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4103080 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4103081 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.3154296875, 
floorSegmentMB=1.291015625, forceMergeDeletesPctAllowed=2.914967029112312, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5272999257306966, deletesPctAllowed=43.33447023651104
   [junit4]   2> 4103115 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@460c33c8[collection1] main]
   [junit4]   2> 4103115 WARN  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4103116 INFO  (coreLoadExecutor-12582-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4103118 WARN  
(recoveryExecutor-12585-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001/tlog/tlog.0000000000000000003
 refcount=2} active=false starting pos=0 inSortedOrder=false
   [junit4]   2> 4103118 INFO  
(searcherExecutor-12583-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@460c33c8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.1):C3/1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758079}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_2(8.3.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758372}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 4103383 INFO  
(recoveryExecutor-12585-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> 4103383 INFO  
(recoveryExecutor-12585-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@21a552a5 commitCommandVersion:0
   [junit4]   2> 4103468 INFO  
(recoveryExecutor-12585-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5a10f30a[collection1] main]
   [junit4]   2> 4103468 INFO  
(recoveryExecutor-12585-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 4103470 INFO  
(searcherExecutor-12583-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5a10f30a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.1):C3/1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758079}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_2(8.3.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758372}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.3.1):C3/1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140760841}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1)))}
   [junit4]   2> 4103471 INFO  
(recoveryExecutor-12585-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[A2 
(1650606220029984768), A3 (1650606220032081920), A4 
(1650606220032081922)],delete=[A2 (-1650606220032081921)]} 0 352
   [junit4]   2> 4103471 WARN  
(recoveryExecutor-12585-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=3 
deletes=1 deleteByQuery=0 errors=0 positionOfStart=0}
   [junit4]   2> 4103471 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=5 status=0 QTime=0
   [junit4]   2> 4103472 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
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> 4103472 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1192702534
   [junit4]   2> 4103472 INFO  (coreCloseExecutor-12588-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@40ef8326
   [junit4]   2> 4103472 INFO  (coreCloseExecutor-12588-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@40ef8326
   [junit4]   2> 4103472 INFO  (coreCloseExecutor-12588-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@77339b26: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7f0632f3
   [junit4]   2> 4103538 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4103538 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4a355d8b: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@3ef9b479
   [junit4]   2> 4103542 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4103542 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@772c0921: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3d508666
   [junit4]   2> 4103545 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4103545 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@23956820: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7810de25
   [junit4]   2> 4103566 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4103634 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4103690 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 4104032 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 4104857 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 4104858 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@38817df3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4104858 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@38817df3[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4104869 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@19bf2536[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4104869 WARN  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@19bf2536[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 4104925 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 4104926 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 4104984 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4105017 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4105017 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c4a0b82
   [junit4]   2> 4105018 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 4105083 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.3.1
   [junit4]   2> 4105121 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 4105316 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field 
id
   [junit4]   2> 4105330 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 4105330 INFO  (coreLoadExecutor-12598-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@7c4a0b82
   [junit4]   2> 4105330 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/build/solr-core/test/J1/temp/solr.search.TestRecovery_542BC50B5B8CBA27-001/data-dir-273-001/]
   [junit4]   2> 4105342 WARN  (coreLoadExecutor-12598-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> 4105484 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 4105484 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 4105486 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 4105486 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 4105499 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=22, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=64.3154296875, 
floorSegmentMB=1.291015625, forceMergeDeletesPctAllowed=2.914967029112312, 
segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5272999257306966, deletesPctAllowed=43.33447023651104
   [junit4]   2> 4105518 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4ea81169[collection1] main]
   [junit4]   2> 4105518 WARN  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.3/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 4105519 INFO  (coreLoadExecutor-12598-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 4105523 INFO  
(searcherExecutor-12599-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4ea81169[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.3.1):C3/1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758079}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1:fieldInfosGen=1:dvGen=2)
 Uninverting(_2(8.3.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140758372}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.3.1):C3/1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.3.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-112-generic, 
timestamp=1574140760841}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1)))}
   [junit4]   2> 4105523 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml} 
hits=5 status=0 QTime=1
   [junit4]   2> 4105524 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=val_i_dvo:2&wt=xml} hits=1 status=0 QTime=0
   [junit4]   2> 4105624 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 4105624 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1591167838
   [junit4]   2> 4105624 INFO  (coreCloseExecutor-12604-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@f2ed9e8
   [junit4]   2> 4105624 INFO  (coreCloseExecutor-12604-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@f2ed9e8
   [junit4]   2> 4105625 INFO  (coreCloseExecutor-12604-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@778e5bc: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@210ea5d9
   [junit4]   2> 4105718 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 4105718 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5f1b314f: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@150ed44a
   [junit4]   2> 4105722 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 4105722 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4e78ac15: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@3d508666
   [junit4]   2> 4105725 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 4105725 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@42b79d59: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@7810de25
   [junit4]   2> 4105751 INFO  
(TEST-TestRecovery.testLogReplay-seed#[542BC50B5B8CBA27]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testLogReplay
   [junit4]   2> 4105768 INFO  
(TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting 
testNewDBQAndDocMatchingOldDBQDuringLogReplay
   [junit4]   2> 4105768 INFO  
(TEST-TestRecovery.testNewDBQAndDocMatchingOldDBQDuringLogReplay-seed#[542BC50B5B8CBA27])
 [     ] o.a.s.SolrTestCaseJ

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

41404, name=recoveryExecutor-12665-thread-1-processing-x:collection1, 
state=BLOCKED, group=TGRP-TestRecovery]
   [junit4]   2>         at 
org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:127)
   [junit4]   2>         at 
org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:653)
   [junit4]   2>         at 
org.apache.solr.update.UpdateLog$LogReplayer.doReplay(UpdateLog.java:1954)
   [junit4]   2>         at 
org.apache.solr.update.UpdateLog$LogReplayer.run(UpdateLog.java:1749)
   [junit4]   2>         at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>         at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>         at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
   [junit4]   2>         at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2>         at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
   [junit4]   2>         at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$10/1221516200.run(Unknown
 Source)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2>    9) Thread[id=41406, 
name=SolrRrdBackendFactory-12660-thread-2, state=WAITING, 
group=TGRP-TestRecovery]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]   2>         at 
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1088)
   [junit4]   2>         at 
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
   [junit4]   2>         at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene80): 
{_root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
 id=PostingsFormat(name=Asserting)}, 
docValues:{_version_=DocValuesFormat(name=Lucene80), 
val_i_dvo=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1316, 
maxMBSortInHeap=6.076809218672068, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1eb5c32f),
 locale=lv, timezone=Pacific/Niue
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_191 (64-bit)/cpus=4,threads=12,free=172189272,total=531628032
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestRandomRequestDistribution, SuggesterWFSTTest, TestSortByMinMaxFunction, 
TestChildDocTransformerHierarchy, BooleanFieldTest, TestWriterPerf, 
SplitByPrefixTest, TestInPlaceUpdatesDistrib, TestCloudConsistency, 
TestComponentsName, TestSimDistribStateManager, SolrCoreCheckLockOnStartupTest, 
RequiredFieldsTest, TestPullReplicaErrorHandling, NodeMutatorTest, 
PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestDeprecatedFilters, 
QueryEqualityTest, TestWordDelimiterFilterFactory, 
HdfsCollectionsAPIDistributedZkTest, CircularListTest, LukeRequestHandlerTest, 
TestLMJelinekMercerSimilarityFactory, TestRetrieveFieldsOptimizer, 
SharedFSAutoReplicaFailoverTest, Tagger2Test, SpatialFilterTest, 
CoreAdminCreateDiscoverTest, HdfsTlogReplayBufferedWhileIndexingTest, 
URLClassifyProcessorTest, SyncSliceTest, TestDFRSimilarityFactory, 
HttpPartitionOnCommitTest, DisMaxRequestHandlerTest, CdcrBidirectionalTest, 
TestFacetMethods, TestReloadAndDeleteDocs, TestFieldCacheWithThreads, 
SplitHandlerTest, TestOnReconnectListenerSupport, 
ClassificationUpdateProcessorFactoryTest, SolrCloudReportersTest, 
ResponseBuilderTest, TestJettySolrRunner, FacetPivotSmallTest, 
TestUnifiedSolrHighlighter, SolrIndexMetricsTest, 
PhrasesIdentificationComponentTest, ReindexCollectionTest, 
TestSimTriggerIntegration, MetricUtilsTest, TestStressReorder, 
TestSolrTestCaseJ4, TestSafeXMLParsing, MinimalSchemaTest, TestSchemaManager, 
RangeFacetCloudTest, TestApiFramework, HdfsAutoAddReplicasIntegrationTest, 
TestBlobHandler, HighlighterMaxOffsetTest, CreateCollectionCleanupTest, 
TestManagedResourceStorage, TestCloudJSONFacetSKG, TestRangeQuery, 
BinaryUpdateRequestHandlerTest, TestFunctionQuery, TestExactSharedStatsCache, 
HdfsNNFailoverTest, TestSolrCloudWithKerberosAlt, 
TestMultiValuedNumericRangeQuery, ScheduledTriggerIntegrationTest, 
DeleteShardTest, NestedAtomicUpdateTest, UpdateRequestProcessorFactoryTest, 
MultiDestinationAuditLoggerTest, RequestHandlersTest, 
TestSchemaSimilarityResource, TestClusterProperties, TestIndexingPerformance, 
DistributedQueryComponentCustomSortTest, TestSha256AuthenticationProvider, 
HdfsRestartWhileUpdatingTest, AuditLoggerIntegrationTest, 
CloneFieldUpdateProcessorFactoryTest, TestSolrIndexConfig, 
TestCorePropertiesReload, ChaosMonkeySafeLeaderWithPullReplicasTest, 
SoftAutoCommitTest, CurrencyFieldTypeTest, TestElisionMultitermQuery, 
TestFieldTypeResource, TestFoldingMultitermQuery, 
VMParamsZkACLAndCredentialsProvidersTest, TestExceedMaxTermLength, 
TestIndexSearcher, ZkStateReaderTest, CdcrReplicationHandlerTest, 
TestMaxTokenLenTokenizer, LeaderFailoverAfterPartitionTest, 
MetricsHistoryHandlerTest, BlockCacheTest, TestFieldSortValues, 
TestUniqueKeyFieldResource, SolrPluginUtilsTest, TestHdfsCloudBackupRestore, 
DistributedVersionInfoTest, TestSubQueryTransformerCrossCore, 
TestCloudRecovery, SolrMetricReporterTest, PathHierarchyTokenizerFactoryTest, 
TestSolrConfigHandler, SuggesterFSTTest, MetricsHistoryWithAuthIntegrationTest, 
HLLUtilTest, ExplicitHLLTest, TlogReplayBufferedWhileIndexingTest, 
TestDocTermOrdsUninvertLimit, TestCursorMarkWithoutUniqueKey, 
TestManagedSynonymGraphFilterFactory, ReturnFieldsTest, RollingRestartTest, 
DirectSolrSpellCheckerTest, TransactionLogTest, 
DistribDocExpirationUpdateProcessorTest, DistributedSpellCheckComponentTest, 
ChaosMonkeyNothingIsSafeTest, AddReplicaTest, MetricsConfigTest, 
AtomicUpdateRemovalJavabinTest, IndexSizeEstimatorTest, UtilsToolTest, 
OverseerStatusTest, TestTolerantUpdateProcessorCloud, 
TestReplicationHandlerBackup, NumericFieldsTest, TestFieldCollectionResource, 
SolrCloudExampleTest, RegexBytesRefFilterTest, TestGraphMLResponseWriter, 
ReplaceNodeTest, TestLegacyFieldReuse, TestBadConfig, EnumFieldTest, 
BasicFunctionalityTest, ConvertedLegacyTest, SampleTest, SolrInfoBeanTest, 
SolrTestCaseJ4Test, TestDistributedMissingSort, TestDistributedSearch, 
TestDocumentBuilder, TestHighlightDedupGrouping, TestJoin, 
TestRandomDVFaceting, TestReversedWildcardFilterFactory, 
TestEmbeddedSolrServerSchemaAPI, AssignBackwardCompatibilityTest, BasicZkTest, 
ChaosMonkeySafeLeaderTest, ClusterStateTest, CollectionPropsTest, 
ConcurrentCreateRoutedAliasTest, ConfigSetsAPITest, ConnectionManagerTest, 
DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, 
LegacyCloudClusterPropTest, MetricsHistoryIntegrationTest, 
MultiThreadedOCPTest, OverseerCollectionConfigSetProcessorTest, 
OverseerModifyCollectionTest, OverseerRolesTest, PeerSyncReplicationTest, 
TestCloudSearcherWarming, TestSSLRandomization, TestStressInPlaceUpdates, 
TestWithCollection, CollectionsAPIAsyncDistributedZkTest, 
SimpleCollectionCreateDeleteTest, TestReplicaProperties, 
TestRequestStatusCollectionAPI, AutoScalingHandlerTest, ComputePlanActionTest, 
MetricTriggerIntegrationTest, NodeAddedTriggerIntegrationTest, 
NodeLostTriggerIntegrationTest, RestoreTriggerStateTest, SystemLogListenerTest, 
TriggerCooldownIntegrationTest, TestSimExtremeIndexing, 
TestSimGenericDistributedQueue, CdcrWithNodesRestartsTest, 
HDFSCollectionsAPITest, BlobRepositoryCloudTest, CachingDirectoryFactoryTest, 
RAMDirectoryFactoryTest, SOLR749Test, TestConfig, TestConfigOverlay, 
TestConfigSetImmutable, TestDynamicLoading, TestMergePolicyConfig, 
TestQuerySenderListener, TestSolrDeletionPolicy2, TestSolrCoreSnapshots, 
DocumentAnalysisRequestHandlerTest, FieldAnalysisRequestHandlerTest, 
JsonLoaderTest, PingRequestHandlerTest, RequestLoggingTest, TestCoreBackup, 
TestRestoreCore, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, 
V2StandaloneTest, XmlUpdateRequestHandlerTest, XsltUpdateRequestHandlerTest, 
AutoscalingHistoryHandlerTest, DebugComponentTest, 
DistributedDebugComponentTest, DistributedFacetPivotLargeTest, 
DistributedFacetPivotWhiteBoxTest, DistributedTermsComponentTest, 
StatsComponentTest, WrapperMergePolicyFactoryTest, CheckHdfsIndexTest, 
PreAnalyzedFieldTest, TestBinaryField, TestBulkSchemaConcurrent, 
TestCollationField, TestHalfAndHalfDocValues, TestManagedSchema, 
TestOmitPositions, TestUseDocValuesAsStored, CursorMarkTest, LargeFieldTest, 
MergeStrategyTest, TestCollapseQParserPlugin, 
TestLegacyNumericRangeQueryBuilder, TestMissingGroups, TestMultiWordSynonyms, 
TestPayloadCheckQParserPlugin, TestPseudoReturnFields, TestRTGBase, 
TestRecovery]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRecovery 
-Dtests.seed=542BC50B5B8CBA27 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=lv -Dtests.timezone=Pacific/Niue -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J1 | TestRecovery (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 
7200000 msec).
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([542BC50B5B8CBA27]:0)
   [junit4] Completed [880/880 (1!)] on J1 in 7212.94s, 4 tests, 2 errors <<< 
FAILURES!

[...truncated 52710 lines...]

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