Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.5/156/

1 tests failed.
FAILED:  
org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication

Error Message:
expected:<1586678370949> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<1586678370949> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([7717E4B622C81A2B:635FBFE301CFA735]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.failNotEquals(Assert.java:834)
        at org.junit.Assert.assertEquals(Assert.java:118)
        at org.junit.Assert.assertEquals(Assert.java:144)
        at 
org.apache.solr.handler.TestReplicationHandler.assertVersions(TestReplicationHandler.java:1138)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:528)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 14357 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> 2008963 INFO  
(SUITE-TestReplicationHandler-seed#[7717E4B622C81A2B]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 2008964 INFO  
(SUITE-TestReplicationHandler-seed#[7717E4B622C81A2B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/data-dir-157-001
   [junit4]   2> 2008964 WARN  
(SUITE-TestReplicationHandler-seed#[7717E4B622C81A2B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 2008964 INFO  
(SUITE-TestReplicationHandler-seed#[7717E4B622C81A2B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2008966 INFO  
(SUITE-TestReplicationHandler-seed#[7717E4B622C81A2B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 2008966 INFO  
(SUITE-TestReplicationHandler-seed#[7717E4B622C81A2B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2008969 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
   [junit4]   2> 2008969 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/collection1
   [junit4]   2> 2008989 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 2008989 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2008989 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2008989 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_191-b12
   [junit4]   2> 2008990 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2008990 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2008990 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2008999 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@12636d94{/solr,null,AVAILABLE}
   [junit4]   2> 2008999 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@2b812cd8{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:38958}
   [junit4]   2> 2008999 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.Server Started @2009092ms
   [junit4]   2> 2008999 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=38958}
   [junit4]   2> 2008999 ERROR 
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2008999 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2009000 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.5.1
   [junit4]   2> 2009000 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2009000 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr
   [junit4]   2> 2009000 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-04-12T07:56:19.666Z
   [junit4]   2> 2009000 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/solr.xml
   [junit4]   2> 2009004 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 2009004 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2009005 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 2018080 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2018081 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@4439a622[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2018081 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@4439a622[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2018085 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@455c7ae9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2018085 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@455c7ae9[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2018086 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 2018113 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2018113 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 2018205 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d
   [junit4]   2> 2018239 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d
   [junit4]   2> 2018239 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d
   [junit4]   2> 2018242 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/.
   [junit4]   2> 2018242 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2018275 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.1
   [junit4]   2> 2018375 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2018391 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field 
id
   [junit4]   2> 2018391 INFO  (coreLoadExecutor-6360-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.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/./collection1,
 trusted=true
   [junit4]   2> 2018391 INFO  (coreLoadExecutor-6360-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@7269006d
   [junit4]   2> 2018391 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 2018413 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=17.2060546875, 
floorSegmentMB=1.759765625, forceMergeDeletesPctAllowed=26.94364798062246, 
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=20.620225687894905
   [junit4]   2> 2018778 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2018778 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2018795 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7457101059163541]
   [junit4]   2> 2018796 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6b221c76[collection1] main]
   [junit4]   2> 2018796 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized 
to use dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-001/collection1/conf
   [junit4]   2> 2018797 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 2018797 INFO  (coreLoadExecutor-6360-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2018799 INFO  
(searcherExecutor-6361-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6b221c76[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2018815 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/collection1
   [junit4]   2> 2018818 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 2018818 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2018818 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2018818 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_191-b12
   [junit4]   2> 2018844 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2018844 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2018844 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2018844 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2453300e{/solr,null,AVAILABLE}
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@14f9756e{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:35620}
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.Server Started @2018938ms
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/collection1/data,
 hostContext=/solr, hostPort=35620}
   [junit4]   2> 2018845 ERROR 
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.5.1
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr
   [junit4]   2> 2018845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-04-12T07:56:29.511Z
   [junit4]   2> 2018846 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/solr.xml
   [junit4]   2> 2018862 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 2018862 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2018884 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 2025319 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2025321 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5007f902[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2025321 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@5007f902[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2025324 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@9334cb4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2025324 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@9334cb4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2025338 WARN  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 2025378 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2025378 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 2025474 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d
   [junit4]   2> 2025579 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d
   [junit4]   2> 2025580 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7269006d
   [junit4]   2> 2025591 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/.
   [junit4]   2> 2025591 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2025607 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.5.1
   [junit4]   2> 2025642 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2025644 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field 
id
   [junit4]   2> 2025644 INFO  (coreLoadExecutor-6373-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.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/./collection1,
 trusted=true
   [junit4]   2> 2025644 INFO  (coreLoadExecutor-6373-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@7269006d
   [junit4]   2> 2025645 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 2025655 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=17.2060546875, 
floorSegmentMB=1.759765625, forceMergeDeletesPctAllowed=26.94364798062246, 
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=20.620225687894905
   [junit4]   2> 2025886 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2025886 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2025887 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7457101059163541]
   [junit4]   2> 2025888 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@179c4588[collection1] main]
   [junit4]   2> 2025888 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized 
to use dir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-002/collection1/conf
   [junit4]   2> 2025902 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 2025902 INFO  (coreLoadExecutor-6373-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2025904 INFO  
(searcherExecutor-6374-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@179c4588[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2025905 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 2025914 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 8
   [junit4]   2> 2025915 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2025915 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14e2b6e5 commitCommandVersion:0
   [junit4]   2> 2025927 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@270f87fd[collection1] main]
   [junit4]   2> 2025927 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2025930 INFO  
(searcherExecutor-6361-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@270f87fd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.5.1, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2025930 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 15
   [junit4]   2> 2025934 WARN  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 2025936 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 2025936 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 2025936 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's version: 1586678196581
   [junit4]   2> 2025936 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 2025936 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's version: 0
   [junit4]   2> 2025936 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 2025951 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
 status=0 QTime=14
   [junit4]   2> 2025951 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 11
   [junit4]   2> 2025952 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=58.53515625, 
floorSegmentMB=1.57421875, forceMergeDeletesPctAllowed=15.790584311011505, 
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=36.57771710995513
   [junit4]   2> 2025953 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2025953 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@b56a508 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc33ef8)
   [junit4]   2> 2025953 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher tmpIndexDir_type  : class 
org.apache.lucene.store.MockDirectoryWrapper , RAMDirectory@b56a508 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc33ef8
   [junit4]   2> 2025954 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025955 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025956 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025957 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025958 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025958 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025967 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025968 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025969 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025970 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapDocFreqInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025970 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2025971 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Bytes downloaded: 1962, Bytes skipped downloading: 0
   [junit4]   2> 2025971 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1962) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@b56a508 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc33ef8)
   [junit4]   2> 2025972 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=56.6962890625, 
floorSegmentMB=1.8134765625, forceMergeDeletesPctAllowed=24.58469403435961, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7395957245337431, deletesPctAllowed=28.592850469156865
   [junit4]   2> 2025973 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2025973 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@44462caf[collection1] main]
   [junit4]   2> 2025976 INFO  (searcherExecutor-6374-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@44462caf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2025976 INFO  (qtp495176354-20787) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:38958/solr/collection1/replication&command=fetchindex}
 status=0 QTime=42
   [junit4]   2> 2025994 INFO  (qtp495176354-20786) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 2025995 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 2025997 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=2
   [junit4]   2> 2025998 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2025999 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2026000 INFO  (qtp495176354-20787) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=1
   [junit4]   2> 2026001 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 2026001 INFO  (qtp495176354-20788) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 2026002 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2026002 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2026002 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2026002 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 0
   [junit4]   2> 2026003 WARN  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 2026004 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 2026004 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 2026004 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's version: 1586678196581
   [junit4]   2> 2026004 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 2026004 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's version: 1586678196581
   [junit4]   2> 2026004 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 2026005 INFO  (qtp495176354-20786) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:38958/solr/collection1/replication&command=fetchindex}
 status=0 QTime=1
   [junit4]   2> 2026006 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 1
   [junit4]   2> 2026007 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2026007 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14e2b6e5 commitCommandVersion:0
   [junit4]   2> 2026033 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@524d01ad[collection1] main]
   [junit4]   2> 2026033 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2026036 INFO  
(searcherExecutor-6361-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@524d01ad[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.5.1, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.5.1):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196686}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2026036 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 29
   [junit4]   2> 2026037 WARN  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 2026039 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 2026039 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 2026039 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's version: 1586678196673
   [junit4]   2> 2026039 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 2026039 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's version: 1586678196581
   [junit4]   2> 2026039 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 2026040 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
 status=0 QTime=0
   [junit4]   2> 2026056 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 21
   [junit4]   2> 2026057 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=58.53515625, 
floorSegmentMB=1.57421875, forceMergeDeletesPctAllowed=15.790584311011505, 
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=36.57771710995513
   [junit4]   2> 2026057 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2026058 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@4f06e39d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47822091)
   [junit4]   2> 2026058 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher tmpIndexDir_type  : class 
org.apache.lucene.store.MockDirectoryWrapper , RAMDirectory@4f06e39d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47822091
   [junit4]   2> 2026059 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_LuceneVarGapDocFreqInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026060 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026069 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026070 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_LuceneVarGapDocFreqInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026070 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026071 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026072 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026073 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026074 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_LuceneVarGapDocFreqInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026074 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026075 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2026076 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Bytes downloaded: 2028, Bytes skipped downloading: 0
   [junit4]   2> 2026076 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2028) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@4f06e39d 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@47822091)
   [junit4]   2> 2026077 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=56.6962890625, 
floorSegmentMB=1.8134765625, forceMergeDeletesPctAllowed=24.58469403435961, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7395957245337431, deletesPctAllowed=28.592850469156865
   [junit4]   2> 2026077 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2026078 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@befcbcc[collection1] main]
   [junit4]   2> 2026081 INFO  (searcherExecutor-6374-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@befcbcc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.5.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196686}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2026089 INFO  (qtp495176354-20787) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:38958/solr/collection1/replication&command=fetchindex}
 status=0 QTime=51
   [junit4]   2> 2026090 INFO  (qtp495176354-20788) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 2026091 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 2026091 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2026092 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2026093 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2026094 INFO  (qtp495176354-20786) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=1
   [junit4]   2> 2026094 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 2026095 INFO  (qtp495176354-20787) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 2026096 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 2026097 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2026097 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14e2b6e5 commitCommandVersion:0
   [junit4]   2> 2026110 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@695e35cf[collection1] realtime]
   [junit4]   2> 2026111 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2026111 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 14
   [junit4]   2> 2026112 WARN  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 2026113 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 2026121 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 2026121 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's version: 1586678196763
   [junit4]   2> 2026121 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 2026121 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's version: 1586678196673
   [junit4]   2> 2026121 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 2026122 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
 status=0 QTime=0
   [junit4]   2> 2026123 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 31
   [junit4]   2> 2026124 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=58.53515625, 
floorSegmentMB=1.57421875, forceMergeDeletesPctAllowed=15.790584311011505, 
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=36.57771710995513
   [junit4]   2> 2026124 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2026124 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2026124 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2027141 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2027141 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2028141 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2028142 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2029142 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2029142 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2030143 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2030143 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2031143 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2031143 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2032144 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2032144 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2033147 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2033147 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2034148 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2034148 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2035148 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2035148 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2036149 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 2036149 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 2037149 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index 
files to be delete-able
   [junit4]   2> 2037150 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@769491c1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@25d27e0f)
   [junit4]   2> 2037150 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher tmpIndexDir_type  : class 
org.apache.lucene.store.MockDirectoryWrapper , RAMDirectory@769491c1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@25d27e0f
   [junit4]   2> 2037153 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037154 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037155 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_LuceneVarGapDocFreqInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037156 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fdm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037157 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_LuceneVarGapDocFreqInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037158 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037171 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037172 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037172 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037173 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_LuceneVarGapDocFreqInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037174 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037174 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Bytes downloaded: 2094, Bytes skipped downloading: 0
   [junit4]   2> 2037174 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2094) : 11 secs (190 bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@769491c1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@25d27e0f)
   [junit4]   2> 2037176 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=56.6962890625, 
floorSegmentMB=1.8134765625, forceMergeDeletesPctAllowed=24.58469403435961, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7395957245337431, deletesPctAllowed=28.592850469156865
   [junit4]   2> 2037176 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2037177 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@100674d6[collection1] main]
   [junit4]   2> 2037192 INFO  (searcherExecutor-6374-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@100674d6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.5.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196686}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.5.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196776}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2037192 INFO  (qtp495176354-20788) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:38958/solr/collection1/replication&command=fetchindex}
 status=0 QTime=11080
   [junit4]   2> 2037211 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 2037212 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
   [junit4]   2> 2037214 INFO  (qtp495176354-20786) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 2037214 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 2037215 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 2037215 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2037215 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14e2b6e5 commitCommandVersion:0
   [junit4]   2> 2037217 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1e3c0e04[collection1] main]
   [junit4]   2> 2037218 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2037267 INFO  
(searcherExecutor-6361-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e3c0e04[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={os=Linux,
 java.vendor=Oracle Corporation, java.version=1.8.0_191, 
java.vm.version=25.191-b12, lucene.version=8.5.1, os.arch=amd64, 
java.runtime.version=1.8.0_191-b12, source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.5.1):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196686}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.5.1):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196776}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.5.1):C1:[diagnostics={os=Linux, java.vendor=Oracle 
Corporation, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678207882}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2037268 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 52
   [junit4]   2> 2037269 WARN  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 2037271 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 2037271 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's generation: 5
   [junit4]   2> 2037271 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Master's version: 1586678207881
   [junit4]   2> 2037271 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's generation: 4
   [junit4]   2> 2037271 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Slave's version: 1586678196763
   [junit4]   2> 2037271 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 2037272 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist}
 status=0 QTime=1
   [junit4]   2> 2037273 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 41
   [junit4]   2> 2037274 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=58.53515625, 
floorSegmentMB=1.57421875, forceMergeDeletesPctAllowed=15.790584311011505, 
segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=36.57771710995513
   [junit4]   2> 2037274 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2037275 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@636aeb1b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@552dfe1)
   [junit4]   2> 2037275 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher tmpIndexDir_type  : class 
org.apache.lucene.store.MockDirectoryWrapper , RAMDirectory@636aeb1b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@552dfe1
   [junit4]   2> 2037276 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037292 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3_LuceneVarGapDocFreqInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037293 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037294 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037295 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fdm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037296 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3_LuceneVarGapDocFreqInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037297 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037298 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037298 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3_LuceneVarGapDocFreqInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037316 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037317 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 2037318 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Bytes downloaded: 2160, Bytes skipped downloading: 0
   [junit4]   2> 2037318 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=2160) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@636aeb1b 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@552dfe1)
   [junit4]   2> 2037319 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=56.6962890625, 
floorSegmentMB=1.8134765625, forceMergeDeletesPctAllowed=24.58469403435961, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7395957245337431, deletesPctAllowed=28.592850469156865
   [junit4]   2> 2037320 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 2037337 INFO  (explicit-fetchindex-cmd) [     ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4762fb2c[collection1] main]
   [junit4]   2> 2037340 INFO  (searcherExecutor-6374-thread-1) [     ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4762fb2c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.5.1):C1:[diagnostics={java.vendor=Oracle
 Corporation, os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196582}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_1(8.5.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196686}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_2(8.5.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678196776}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
 Uninverting(_3(8.5.1):C1:[diagnostics={java.vendor=Oracle Corporation, 
os=Linux, java.version=1.8.0_191, java.vm.version=25.191-b12, 
lucene.version=8.5.1, os.arch=amd64, java.runtime.version=1.8.0_191-b12, 
source=flush, os.version=4.4.0-170-generic, 
timestamp=1586678207882}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
   [junit4]   2> 2037340 INFO  (qtp495176354-20787) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:38958/solr/collection1/replication&command=fetchindex}
 status=0 QTime=71
   [junit4]   2> 2037351 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} 
hits=4 status=0 QTime=0
   [junit4]   2> 2037351 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 2037352 INFO  (qtp495176354-20788) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} 
hits=4 status=0 QTime=0
   [junit4]   2> 2037352 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 2037373 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2037390 INFO  (qtp1182927864-20766) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2037391 INFO  (qtp1182927864-20767) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 2037392 INFO  (qtp495176354-20786) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=1
   [junit4]   2> 2037392 INFO  (qtp1182927864-20765) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 2037393 INFO  (qtp495176354-20787) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 2037393 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
   [junit4]   2> 2037393 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=143431161
   [junit4]   2> 2037402 INFO  (coreCloseExecutor-6383-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@385ea4f3
   [junit4]   2> 2037402 INFO  (coreCloseExecutor-6383-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@385ea4f3
   [junit4]   2> 2037402 INFO  (coreCloseExecutor-6383-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@5e41668: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4e79673a
   [junit4]   2> 2037442 INFO  (coreCloseExecutor-6383-thread-1) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  
... SKIPPED (unnecessary).
   [junit4]   2> 2037443 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2037443 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@62c8e4f4: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@31ac3c60
   [junit4]   2> 2037449 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2037449 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3dc389c: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5ab7df9a
   [junit4]   2> 2037473 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2037473 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6642f0ab: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@4517d50c
   [junit4]   2> 2037475 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@2b812cd8{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 2037475 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@12636d94{/solr,null,UNAVAILABLE}
   [junit4]   2> 2037475 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2037480 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1351522348
   [junit4]   2> 2037500 INFO  (coreCloseExecutor-6385-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2abbafc4
   [junit4]   2> 2037500 INFO  (coreCloseExecutor-6385-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@2abbafc4
   [junit4]   2> 2037559 INFO  (coreCloseExecutor-6385-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2d95e039: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@37e90eef
   [junit4]   2> 2037638 INFO  (coreCloseExecutor-6385-thread-1) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  
... SKIPPED (unnecessary).
   [junit4]   2> 2037640 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2037640 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d0c0e95: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@3ce9f93e
   [junit4]   2> 2037668 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2037668 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@162312fa: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5ab7df9a
   [junit4]   2> 2037684 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2037684 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@42612c0: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@4517d50c
   [junit4]   2> 2037687 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@14f9756e{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 2037687 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2453300e{/solr,null,UNAVAILABLE}
   [junit4]   2> 2037687 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2037728 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting 
testShouldReportErrorWhenRequiredCommandArgMissing
   [junit4]   2> 2037729 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001/solr-instance-003/collection1
   [junit4]   2> 2037731 WARN  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short 
content, break even is size 23
   [junit4]   2> 2037731 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=0)
   [junit4]   2> 2037731 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 
1 ...
   [junit4]   2> 2037731 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 
2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 
1.8.0_191-b12
   [junit4]   2> 2037732 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2037732 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2037732 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2037733 INFO  
(TEST-TestReplicationHandler.testShouldReportErrorWhenRequiredCommandArgMissing-seed#[7717E4B622C81A2B])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@34f268ec{/solr,null,AVAILABLE}
   [junit4]   2> 2037733 INFO  (TEST-TestReplicationHandler.testShouldRepor

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

g=SolrCore@71967df2
   [junit4]   2> 2247547 INFO  (coreCloseExecutor-7361-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@436a9f78: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@75022dcc
   [junit4]   2> 2247550 INFO  (coreCloseExecutor-7361-thread-1) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  
... SKIPPED (unnecessary).
   [junit4]   2> 2247551 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2247551 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7517c046: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@54551cdc
   [junit4]   2> 2247553 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2247553 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3f167fc5: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5ab7df9a
   [junit4]   2> 2247554 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2247554 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3aa31303: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@4517d50c
   [junit4]   2> 2247555 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@4bc9d1ac{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 2247556 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@29112e74{/solr,null,UNAVAILABLE}
   [junit4]   2> 2247556 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2247556 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=164367541
   [junit4]   2> 2247557 INFO  (coreCloseExecutor-7363-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@11790d72
   [junit4]   2> 2247557 INFO  (coreCloseExecutor-7363-thread-1) [    
x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=SolrCore@11790d72
   [junit4]   2> 2247557 INFO  (coreCloseExecutor-7363-thread-1) [    
x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3895fcfc: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1a7c4fee
   [junit4]   2> 2247560 INFO  (coreCloseExecutor-7363-thread-1) [    
x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  
... SKIPPED (unnecessary).
   [junit4]   2> 2247561 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2247561 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@18a4c309: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@2dff2096
   [junit4]   2> 2247563 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2247563 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7e6f4ecf: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@5ab7df9a
   [junit4]   2> 2247563 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2247563 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@731f9e7: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@4517d50c
   [junit4]   2> 2247565 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@50ee29fe{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:0}
   [junit4]   2> 2247565 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@18f10e6a{/solr,null,UNAVAILABLE}
   [junit4]   2> 2247565 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[7717E4B622C81A2B]) [     ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.5/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_7717E4B622C81A2B-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene84): 
{name=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
id=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
newname=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{}, 
maxPointsInLeafNode=241, maxMBSortInHeap=7.062656907460913, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@6acdb8b1),
 locale=es-CL, timezone=Australia/Lord_Howe
   [junit4]   2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 
1.8.0_191 (64-bit)/cpus=8,threads=1,free=251920008,total=529530880
   [junit4]   2> NOTE: All tests run in this JVM: [TestDistributedGrouping, 
TestMultiValuedNumericRangeQuery, PeerSyncWithBufferUpdatesTest, 
TestCorePropertiesReload, TestSimPolicyCloud, AuditLoggerPluginTest, 
TestFieldCache, DirectoryFactoryTest, PropertiesRequestHandlerTest, 
ShowFileRequestHandlerTest, V2StandaloneTest, CreateCollectionCleanupTest, 
BitVectorTest, RuleEngineTest, TestFieldSortValues, TestDistribPackageStore, 
TestEmbeddedSolrServerConstructors, TestXmlQParserPlugin, TestLRUCache, 
TestHighFrequencyDictionaryFactory, TestDocBasedVersionConstraints, 
HighlighterWithoutStoredIdTest, TestBulkSchemaAPI, SparseHLLTest, 
NodeMutatorTest, TestStressRecovery, HttpSolrCallGetCoreTest, 
SolrCoreCheckLockOnStartupTest, TestLeaderElectionWithEmptyReplica, 
TestSimLargeCluster, NotRequiredUniqueKeyTest, LeaderElectionContextKeyTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
DistributedSpellCheckComponentTest, DistributedVersionInfoTest, 
HighlighterConfigTest, ReturnFieldsTest, TestLocalFSCloudBackupRestore, 
TestFileDictionaryLookup, CdcrReplicationHandlerTest, RequestHandlersTest, 
TestSimpleQParserPlugin, TestIndexSearcher, MoveReplicaHDFSFailoverTest, 
DocExpirationUpdateProcessorFactoryTest, DocumentAnalysisRequestHandlerTest, 
QueryEqualityTest, TestLuceneMatchVersion, TestCollectionAPIs, 
TestCloudNestedDocsSort, CollectionPropsTest, TestIntervalFaceting, 
TestCloudConsistency, DistributedFacetPivotLargeTest, TestChildDocTransformer, 
SchemaVersionSpecificBehaviorTest, CloudMLTQParserTest, OutputWriterTest, 
HdfsRestartWhileUpdatingTest, PrimUtilsTest, OverseerTaskQueueTest, 
BasicDistributedZkTest, DeleteShardTest, BlockDirectoryTest, 
DistributedIntervalFacetingTest, ZkStateReaderTest, 
ConcurrentCreateRoutedAliasTest, TestCollectionAPI, 
DeleteLastCustomShardedReplicaTest, TestManagedResource, 
PhrasesIdentificationComponentTest, SubstringBytesRefFilterTest, 
MetricsConfigTest, ConcurrentDeleteAndCreateCollectionTest, 
MoreLikeThisHandlerTest, TestPayloadScoreQParserPlugin, 
CoreMergeIndexesAdminHandlerTest, TestLegacyFieldReuse, 
TestFieldTypeCollectionResource, TestAnalyzeInfixSuggestions, 
TestSchemalessBufferedUpdates, TestFieldCacheSortRandom, DOMUtilTest, 
SolrRequestParserTest, TestUnInvertedFieldException, 
DefaultValueUpdateProcessorTest, TimeRoutedAliasUpdateProcessorTest, 
LeaderFailoverAfterPartitionTest, ZkCLITest, TestSolrIndexConfig, 
TestSQLHandlerNonCloud, HLLSerializationTest, TestMacros, ShardSplitTest, 
CoreAdminHandlerTest, TestDocumentBuilder, DistributedFacetPivotSmallTest, 
TestNRTOpen, TestTlogReplayVsRecovery, ForceLeaderWithTlogReplicasTest, 
TestPHPSerializedResponseWriter, TestClusterProperties, TestPackages, 
BadIndexSchemaTest, PrimitiveFieldTypeTest, NodeLostTriggerTest, 
TestDynamicFieldResource, TestSerializedLuceneMatchVersion, 
TestUseDocValuesAsStored, SaslZkACLProviderTest, 
TestTolerantUpdateProcessorRandomCloud, CdcrBidirectionalTest, 
SpatialHeatmapFacetsTest, TestHttpServletCarrier, SynonymTokenizerTest, 
HdfsBasicDistributedZkTest, ClusterStateMockUtilTest, CursorMarkTest, 
TestReplicationHandler]
   [junit4] Completed [393/900 (1!)] on J2 in 238.80s, 21 tests, 1 failure <<< 
FAILURES!

[...truncated 54300 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to