Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18169/
Java: 32bit/jdk-9-ea+140 -client -XX:+UseG1GC

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

Error Message:
[snapshot_metadata, index.20161029191909112, replication.properties, 
index.properties, index.20161029191907184] expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: [snapshot_metadata, index.20161029191909112, 
replication.properties, index.properties, index.20161029191907184] expected:<1> 
but was:<2>
        at 
__randomizedtesting.SeedInfo.seed([B06F9660B0CAAE5E:6BC496A6B5E2C7ED]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at 
org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:907)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:874)
        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native 
Method)
        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
        at 
jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)




Build Log:
[...truncated 11649 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/init-core-data-001
   [junit4]   2> 964335 INFO  
(SUITE-TestReplicationHandler-seed#[B06F9660B0CAAE5E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
   [junit4]   2> 964336 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting doTestDetails
   [junit4]   2> 964337 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/collection1
   [junit4]   2> 964346 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 964347 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1402862{/solr,null,AVAILABLE}
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@16b71d7{HTTP/1.1,[http/1.1]}{127.0.0.1:46800}
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.Server Started @966521ms
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/collection1/data,
 hostPort=46800}
   [junit4]   2> 964350 ERROR 
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 964350 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-29T13:18:00.614841Z
   [junit4]   2> 964351 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/solr.xml
   [junit4]   2> 964383 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/.
   [junit4]   2> 964383 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 964391 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 964398 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 964399 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 964399 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/./collection1
   [junit4]   2> 964400 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 964401 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=3.4189453125, 
floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=22.93137984728609, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 964405 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 964405 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 964405 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 964406 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1dcb30d[collection1] main]
   [junit4]   2> 964406 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-001/collection1/conf
   [junit4]   2> 964407 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.h.ReplicationHandler Replication enabled for following config files: 
schema.xml,xslt/dummy.xsl
   [junit4]   2> 964407 INFO  (coreLoadExecutor-2989-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 964407 INFO  (searcherExecutor-2990-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1dcb30d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 964886 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/collection1
   [junit4]   2> 964900 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 964900 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1ff1d7f{/solr,null,AVAILABLE}
   [junit4]   2> 964901 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@1f7dc2{HTTP/1.1,[http/1.1]}{127.0.0.1:41311}
   [junit4]   2> 964901 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.Server Started @967073ms
   [junit4]   2> 964901 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/collection1/data,
 hostPort=41311}
   [junit4]   2> 964901 ERROR 
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 964901 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 964901 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 964901 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 964902 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-29T13:18:01.166012Z
   [junit4]   2> 964902 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/solr.xml
   [junit4]   2> 964923 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/.
   [junit4]   2> 964923 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 964932 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 964938 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 964939 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 964940 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/./collection1
   [junit4]   2> 964941 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 964941 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=3.4189453125, 
floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=22.93137984728609, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 964946 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 964947 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 964947 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 964947 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@15fd9d1[collection1] main]
   [junit4]   2> 964948 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-002/collection1/conf
   [junit4]   2> 964950 INFO  (coreLoadExecutor-2999-thread-1) [    ] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 964950 INFO  (searcherExecutor-3000-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15fd9d1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 965329 INFO  (qtp3104746-6688) [    ] 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> 965329 INFO  (indexFetcher-3004-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 965330 INFO  (indexFetcher-3004-thread-1) [    ] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 965330 INFO  (indexFetcher-3004-thread-1) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 965429 INFO  (qtp3104746-6690) [    ] 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> 965429 INFO  (qtp3104746-6683) [    ] 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> 965430 INFO  (qtp3104746-6689) [    ] 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> 965431 INFO  (qtp30011806-6705) [    ] 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> 965432 INFO  (qtp3104746-6688) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[0]} 0 0
   [junit4]   2> 965432 INFO  (qtp3104746-6690) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 965432 INFO  (qtp3104746-6690) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@27e6b0
   [junit4]   2> 965434 INFO  (qtp3104746-6690) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25e09f[collection1] main]
   [junit4]   2> 965434 INFO  (qtp3104746-6690) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 965435 INFO  (searcherExecutor-2990-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@25e09f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 965435 INFO  (qtp3104746-6690) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 2
   [junit4]   2> 965436 WARN  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 965439 INFO  (qtp3104746-6684) [    ] 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> 965439 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 965439 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Master's version: 1477747081696
   [junit4]   2> 965439 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 965439 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 965445 INFO  (qtp3104746-6689) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=5
   [junit4]   2> 965445 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 965446 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=57.533203125, 
floorSegmentMB=1.765625, forceMergeDeletesPctAllowed=5.175876975824659, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 965446 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 965446 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@1e089d1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a9ca2a)
   [junit4]   2> 965447 INFO  (qtp3104746-6688) [    ] 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> 965448 INFO  (qtp3104746-6690) [    ] 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> 965448 INFO  (qtp3104746-6684) [    ] 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> 965450 INFO  (qtp3104746-6689) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965450 INFO  (qtp3104746-6688) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965451 INFO  (qtp3104746-6690) [    ] 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> 965451 INFO  (qtp3104746-6684) [    ] 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> 965452 INFO  (qtp3104746-6689) [    ] 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> 965452 INFO  (qtp3104746-6688) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965453 INFO  (qtp3104746-6690) [    ] 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> 965453 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1727) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@1e089d1 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a9ca2a)
   [junit4]   2> 965455 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.549346282580318]
   [junit4]   2> 965456 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 965456 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e9122f[collection1] main]
   [junit4]   2> 965457 INFO  (searcherExecutor-3000-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e9122f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 965457 INFO  (qtp30011806-6707) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:46800/solr/collection1/replication&command=fetchindex}
 status=0 QTime=21
   [junit4]   2> 965458 INFO  (qtp3104746-6684) [    ] 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> 965459 INFO  (qtp30011806-6706) [    ] 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> 965459 INFO  (qtp3104746-6683) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 965462 INFO  (qtp3104746-6688) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 965462 INFO  (qtp3104746-6688) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@27e6b0
   [junit4]   2> 965464 INFO  (qtp3104746-6688) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@710b5a[collection1] main]
   [junit4]   2> 965464 INFO  (qtp3104746-6688) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 965464 INFO  (searcherExecutor-2990-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@710b5a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1)))}
   [junit4]   2> 965464 INFO  (qtp3104746-6688) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 1
   [junit4]   2> 965465 WARN  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 965467 INFO  (qtp3104746-6689) [    ] 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> 965467 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 965467 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Master's version: 1477747081726
   [junit4]   2> 965467 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 965467 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 965476 INFO  (qtp3104746-6684) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=8
   [junit4]   2> 965476 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 19
   [junit4]   2> 965478 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=57.533203125, 
floorSegmentMB=1.765625, forceMergeDeletesPctAllowed=5.175876975824659, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 965478 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 965478 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@1c1e189 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bd2c3b)
   [junit4]   2> 965478 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 965478 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 965478 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 965479 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tio because it 
already exists
   [junit4]   2> 965479 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.doc because it 
already exists
   [junit4]   2> 965479 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 965479 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 965479 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 965479 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tipo because it 
already exists
   [junit4]   2> 965479 INFO  (qtp3104746-6683) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965480 INFO  (qtp3104746-6688) [    ] 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> 965481 INFO  (qtp3104746-6689) [    ] 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> 965481 INFO  (qtp3104746-6684) [    ] 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> 965482 INFO  (qtp3104746-6683) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965483 INFO  (qtp3104746-6688) [    ] 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> 965483 INFO  (qtp3104746-6689) [    ] 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> 965484 INFO  (qtp3104746-6684) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965484 INFO  (qtp3104746-6683) [    ] 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> 965485 INFO  (qtp3104746-6688) [    ] 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> 965500 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1790) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@1c1e189 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@bd2c3b)
   [junit4]   2> 965503 WARN  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Exception while updating statistics
   [junit4]   2> java.io.IOException: file "replication.properties" was already 
written to
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
   [junit4]   2>        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 965509 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.549346282580318]
   [junit4]   2> 965511 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 965511 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@136b6f1[collection1] main]
   [junit4]   2> 965511 INFO  (searcherExecutor-3000-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@136b6f1[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1)))}
   [junit4]   2> 965511 INFO  (qtp30011806-6700) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:46800/solr/collection1/replication&command=fetchindex}
 status=0 QTime=47
   [junit4]   2> 965513 INFO  (qtp3104746-6689) [    ] 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> 965513 INFO  (qtp30011806-6705) [    ] 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> 965514 INFO  (qtp3104746-6690) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 965514 INFO  (qtp3104746-6683) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 965514 INFO  (qtp3104746-6683) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@27e6b0
   [junit4]   2> 965516 INFO  (qtp3104746-6683) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ed5df4[collection1] main]
   [junit4]   2> 965516 INFO  (qtp3104746-6683) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 965516 INFO  (searcherExecutor-2990-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ed5df4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 965516 INFO  (qtp3104746-6683) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 1
   [junit4]   2> 965517 WARN  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 965519 INFO  (qtp3104746-6684) [    ] 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> 965519 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 965519 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Master's version: 1477747081778
   [junit4]   2> 965519 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 965519 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 965539 INFO  (qtp3104746-6689) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=19
   [junit4]   2> 965539 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 28
   [junit4]   2> 965541 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=57.533203125, 
floorSegmentMB=1.765625, forceMergeDeletesPctAllowed=5.175876975824659, 
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 965542 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@176f9c4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75389d)
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tio because it 
already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.doc because it 
already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tipo because it 
already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1_BlockTreeOrds_0.tipo because it 
already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1_BlockTreeOrds_0.doc because it 
already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1_BlockTreeOrds_0.tio because it 
already exists
   [junit4]   2> 965543 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
   [junit4]   2> 965544 INFO  (qtp3104746-6690) [    ] 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> 965547 INFO  (qtp3104746-6683) [    ] 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> 965548 INFO  (qtp3104746-6684) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965548 INFO  (qtp3104746-6689) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965549 INFO  (qtp3104746-6690) [    ] 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> 965549 INFO  (qtp3104746-6683) [    ] 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> 965550 INFO  (qtp3104746-6684) [    ] 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> 965550 INFO  (qtp3104746-6689) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 965551 INFO  (qtp3104746-6690) [    ] 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> 965551 INFO  (qtp3104746-6683) [    ] 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> 965551 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1853) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@176f9c4 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75389d)
   [junit4]   2> 965553 WARN  (explicit-fetchindex-cmd) [    ] 
o.a.s.h.IndexFetcher Exception while updating statistics
   [junit4]   2> java.io.IOException: file "replication.properties" was already 
written to
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:689)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:501)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:265)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
   [junit4]   2>        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 965554 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.549346282580318]
   [junit4]   2> 965554 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 965556 INFO  (explicit-fetchindex-cmd) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14db24e[collection1] main]
   [junit4]   2> 965556 INFO  (searcherExecutor-3000-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14db24e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 965556 INFO  (qtp30011806-6701) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:46800/solr/collection1/replication&command=fetchindex}
 status=0 QTime=39
   [junit4]   2> 965558 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/collection1
   [junit4]   2> 965580 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 965582 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2c6780{/solr,null,AVAILABLE}
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@da7add{HTTP/1.1,[http/1.1]}{127.0.0.1:46160}
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.Server Started @967754ms
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/collection1/data,
 hostPort=46160}
   [junit4]   2> 965583 ERROR 
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.0.0
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 965583 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-29T13:18:01.847732Z
   [junit4]   2> 965584 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/solr.xml
   [junit4]   2> 965603 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/.
   [junit4]   2> 965603 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 965612 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 965624 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 965625 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 965626 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/./collection1
   [junit4]   2> 965627 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/./collection1/data/]
   [junit4]   2> 965628 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=3.4189453125, 
floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=22.93137984728609, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 965633 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 965633 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 965633 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 965633 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@9b37c4[collection1] main]
   [junit4]   2> 965634 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-003/collection1/conf
   [junit4]   2> 965634 WARN  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 965636 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.h.ReplicationHandler  No value set for 'pollInterval'. Timer Task not 
started.
   [junit4]   2> 965636 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.h.ReplicationHandler Replication enabled for following config files: 
schema.xml
   [junit4]   2> 965636 INFO  (coreLoadExecutor-3013-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 965636 INFO  (searcherExecutor-3014-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@9b37c4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 966108 WARN  (qtp20056615-6732) [    ] 
o.a.s.h.ReplicationHandler Exception while invoking 'details' method for 
replication on master 
   [junit4]   2> 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:46800/solr: Expected mime type 
application/octet-stream but got text/html. <html>
   [junit4]   2> <head>
   [junit4]   2> <meta http-equiv="Content-Type" 
content="text/html;charset=ISO-8859-1"/>
   [junit4]   2> <title>Error 404 </title>
   [junit4]   2> </head>
   [junit4]   2> <body>
   [junit4]   2> <h2>HTTP ERROR: 404</h2>
   [junit4]   2> <p>Problem accessing /solr/replication. Reason:
   [junit4]   2> <pre>    Can not find: /solr/replication</pre></p>
   [junit4]   2> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 
9.3.8.v20160314</a><hr/>
   [junit4]   2> </body>
   [junit4]   2> </html>
   [junit4]   2> 
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:578)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
   [junit4]   2>        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
   [junit4]   2>        at 
org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1726)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:938)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:153)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2229)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:654)
   [junit4]   2>        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:460)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
   [junit4]   2>        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
   [junit4]   2>        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1160)
   [junit4]   2>        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
   [junit4]   2>        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1092)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
   [junit4]   2>        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2>        at 
org.eclipse.jetty.server.Server.handle(Server.java:518)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
   [junit4]   2>        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]   2>        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 966110 INFO  (qtp20056615-6732) [    ] 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=3
   [junit4]   2> 966111 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@da7add{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 966111 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=31039865
   [junit4]   2> 966111 INFO  (coreCloseExecutor-3018-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@184bdb6
   [junit4]   2> 966112 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2c6780{/solr,null,UNAVAILABLE}
   [junit4]   2> 966113 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending doTestDetails
   [junit4]   2> 966113 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@16b71d7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 966113 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=26330186
   [junit4]   2> 966114 INFO  (coreCloseExecutor-3020-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1372a6a
   [junit4]   2> 966114 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1402862{/solr,null,UNAVAILABLE}
   [junit4]   2> 966115 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.ServerConnector Stopped 
ServerConnector@1f7dc2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 966115 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=474942
   [junit4]   2> 966115 INFO  (coreCloseExecutor-3022-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@19c2ff1
   [junit4]   2> 966116 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[B06F9660B0CAAE5E]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1ff1d7f{/solr,null,UNAVAILABLE}
   [junit4]   2> 966118 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRateLimitedReplication
   [junit4]   2> 966119 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1
   [junit4]   2> 966125 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 966126 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1592c0a{/solr,null,AVAILABLE}
   [junit4]   2> 966126 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@19684e5{HTTP/1.1,[http/1.1]}{127.0.0.1:37628}
   [junit4]   2> 966126 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.Server Started @968298ms
   [junit4]   2> 966126 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1/data,
 hostPort=37628}
   [junit4]   2> 966126 ERROR 
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 966127 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 7.0.0
   [junit4]   2> 966127 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone 
mode on port null
   [junit4]   2> 966127 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 966127 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-29T13:18:02.391219Z
   [junit4]   2> 966127 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/solr.xml
   [junit4]   2> 966156 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/.
   [junit4]   2> 966156 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 966172 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 966178 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 966179 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 966180 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/./collection1
   [junit4]   2> 966180 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/./collection1/data/]
   [junit4]   2> 966182 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=3.4189453125, 
floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=22.93137984728609, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 966186 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 966186 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 966186 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 966186 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@b7ed25[collection1] main]
   [junit4]   2> 966187 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1/conf
   [junit4]   2> 966188 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.h.ReplicationHandler Replication enabled for following config files: 
schema.xml,xslt/dummy.xsl
   [junit4]   2> 966188 INFO  (coreLoadExecutor-3029-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 966188 INFO  (searcherExecutor-3030-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@b7ed25[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 966670 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/collection1
   [junit4]   2> 966695 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 966696 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@30b97b{/solr,null,AVAILABLE}
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@17c5601{HTTP/1.1,[http/1.1]}{127.0.0.1:45782}
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.Server Started @968870ms
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/collection1/data,
 hostPort=45782}
   [junit4]   2> 966698 ERROR 
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 7.0.0
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone 
mode on port null
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 966698 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-29T13:18:02.962964Z
   [junit4]   2> 966699 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/solr.xml
   [junit4]   2> 966716 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/.
   [junit4]   2> 966716 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 966727 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 966738 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 966740 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 966741 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/./collection1
   [junit4]   2> 966741 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/./collection1/data/]
   [junit4]   2> 966743 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=50, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=3.4189453125, 
floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=22.93137984728609, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 966747 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 966747 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 966747 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 966748 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@cc6f98[collection1] main]
   [junit4]   2> 966748 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-005/collection1/conf
   [junit4]   2> 966750 INFO  (coreLoadExecutor-3039-thread-1) [    ] 
o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 966751 INFO  (searcherExecutor-3040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@cc6f98[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 967220 INFO  (qtp3591705-6753) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 967221 INFO  (qtp3591705-6753) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
   [junit4]   2> 967221 INFO  (qtp28639148-6770) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 967221 INFO  (qtp28639148-6770) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
   [junit4]   2> 967222 INFO  (qtp3591705-6754) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 967222 INFO  (qtp3591705-6754) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@96edfe
   [junit4]   2> 967223 INFO  (qtp3591705-6754) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@16c0d27[collection1] main]
   [junit4]   2> 967223 INFO  (qtp3591705-6754) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 967223 INFO  (searcherExecutor-3030-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@16c0d27[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 967223 INFO  (qtp3591705-6754) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 1
   [junit4]   2> 967223 INFO  (qtp28639148-6771) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 967223 INFO  (qtp28639148-6771) [    ] o.a.s.u.SolrIndexWriter 
Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@16f7684
   [junit4]   2> 967224 INFO  (qtp28639148-6771) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@959262[collection1] main]
   [junit4]   2> 967224 INFO  (qtp28639148-6771) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 967224 INFO  (searcherExecutor-3040-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@959262[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 967224 INFO  (qtp28639148-6771) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 967224 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@19684e5{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 967224 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5790582
   [junit4]   2> 967225 INFO  (coreCloseExecutor-3045-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3a4c04
   [junit4]   2> 967226 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1592c0a{/solr,null,UNAVAILABLE}
   [junit4]   2> 967227 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@17c5601{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 967227 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=904500
   [junit4]   2> 967227 INFO  (coreCloseExecutor-3047-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@186a54f
   [junit4]   2> 967228 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@30b97b{/solr,null,UNAVAILABLE}
   [junit4]   2> 967234 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 967235 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@8c24d4{/solr,null,AVAILABLE}
   [junit4]   2> 967235 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@1bf2ae8{HTTP/1.1,[http/1.1]}{127.0.0.1:41254}
   [junit4]   2> 967235 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.e.j.s.Server Started @969407ms
   [junit4]   2> 967235 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1/data,
 hostPort=41254}
   [junit4]   2> 967236 ERROR 
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 967236 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ 
version 7.0.0
   [junit4]   2> 967236 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone 
mode on port null
   [junit4]   2> 967236 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 967236 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2016-10-29T13:18:03.500430Z
   [junit4]   2> 967237 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/solr.xml
   [junit4]   2> 967257 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/.
   [junit4]   2> 967257 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[B06F9660B0CAAE5E])
 [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 967267 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 967275 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 967276 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 967277 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/./collection1
   [junit4]   2> 967277 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/./collection1/data/]
   [junit4]   2> 967287 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=10.8037109375, 
floorSegmentMB=2.1572265625, forceMergeDeletesPctAllowed=25.627572161284224, 
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 967336 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 967336 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 967337 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=12, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5412262573165193]
   [junit4]   2> 967338 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@11b8009[collection1] main]
   [junit4]   2> 967339 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-004/collection1/conf
   [junit4]   2> 967339 INFO  (coreLoadExecutor-3054-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 967340 INFO  (searcherExecutor-3055-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11b8009[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 967763 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[0]} 0 1
   [junit4]   2> 967764 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 967765 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 967766 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 967771 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4]} 0 4
   [junit4]   2> 967771 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 967772 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[6]} 0 0
   [junit4]   2> 967772 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[7]} 0 0
   [junit4]   2> 967773 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[8]} 0 0
   [junit4]   2> 967778 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[9]} 0 5
   [junit4]   2> 967779 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[10]} 0 0
   [junit4]   2> 967779 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[11]} 0 0
   [junit4]   2> 967780 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[12]} 0 0
   [junit4]   2> 967780 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[13]} 0 0
   [junit4]   2> 967785 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[14]} 0 4
   [junit4]   2> 967785 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[15]} 0 0
   [junit4]   2> 967786 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[16]} 0 0
   [junit4]   2> 967786 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[17]} 0 0
   [junit4]   2> 967787 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[18]} 0 0
   [junit4]   2> 967787 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[19]} 0 0
   [junit4]   2> 967787 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[20]} 0 0
   [junit4]   2> 967792 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[21]} 0 4
   [junit4]   2> 967792 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[22]} 0 0
   [junit4]   2> 967793 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[23]} 0 0
   [junit4]   2> 967793 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[24]} 0 0
   [junit4]   2> 967794 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[25]} 0 0
   [junit4]   2> 967794 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[26]} 0 0
   [junit4]   2> 967795 INFO  (qtp25457643-6787) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[27]} 0 0
   [junit4]   2> 967796 INFO  (qtp25457643-6792) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[28]} 0 1
   [junit4]   2> 967797 INFO  (qtp25457643-6794) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[29]} 0 0
   [junit4]   2> 967797 INFO  (qtp25457643-6793) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [col

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

 org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
   [junit4]   2>        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2>        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2>        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
   [junit4]   2>        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
   [junit4]   2>        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 
   [junit4]   2> 1034439 ERROR 
(OldIndexDirectoryCleanupThreadForCore-collection1) [    ] 
o.a.s.c.DirectoryFactory Failed to delete old directory 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-030/./collection1/data/index.20161029191907184
 due to: java.io.FileNotFoundException: File does not exist: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001/solr-instance-030/./collection1/data/index.20161029191907184/segments_6
   [junit4]   2> 1034488 INFO  (qtp22874162-7640) [    ] o.a.s.s.HttpSolrCall 
[admin] webapp=null path=/admin/cores 
params={core=collection1&action=status&wt=javabin&version=2} status=0 QTime=46
   [junit4]   2> 1034490 INFO  (qtp22874162-7639) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/solr path=/select 
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=490 status=0 QTime=0
   [junit4]   2> 1034490 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 490 docs
   [junit4]   2> 1034513 INFO  (qtp1441693-7651) [    ] 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=22
   [junit4]   2> 1034545 INFO  (qtp1441693-7656) [    ] 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=31
   [junit4]   2> 1034566 INFO  (qtp22874162-7640) [    ] 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=19
   [junit4]   2> 1034566 INFO  (qtp1441693-7657) [    ] 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> 1034566 INFO  (qtp22874162-7636) [    ] 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]   1> [snapshot_metadata, index]
   [junit4]   1> [snapshot_metadata, index.20161029191909112, 
replication.properties, index.properties, index.20161029191907184]
   [junit4]   2> 1034570 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.a.s.SolrTestCaseJ4 ###Ending doTestStressReplication
   [junit4]   2> 1034571 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.e.j.s.ServerConnector Stopped 
ServerConnector@119bf9e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1034571 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=10897119
   [junit4]   2> 1034571 INFO  (coreCloseExecutor-3633-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@b37602
   [junit4]   2> 1034573 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1d6fcd2{/solr,null,UNAVAILABLE}
   [junit4]   2> 1034574 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.e.j.s.ServerConnector Stopped 
ServerConnector@14a451b{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1034574 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=25931269
   [junit4]   2> 1034574 INFO  (coreCloseExecutor-3635-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@ee3def
   [junit4]   2> 1034588 INFO  
(TEST-TestReplicationHandler.doTestStressReplication-seed#[B06F9660B0CAAE5E]) [ 
   ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1ab1181{/solr,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandler -Dtests.method=doTestStressReplication 
-Dtests.seed=B06F9660B0CAAE5E -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=is -Dtests.timezone=Asia/Omsk -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 7.62s J1 | TestReplicationHandler.doTestStressReplication 
<<<
   [junit4]    > Throwable #1: java.lang.AssertionError: [snapshot_metadata, 
index.20161029191909112, replication.properties, index.properties, 
index.20161029191907184] expected:<1> but was:<2>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([B06F9660B0CAAE5E:6BC496A6B5E2C7ED]:0)
   [junit4]    >        at 
org.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:907)
   [junit4]    >        at 
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:874)
   [junit4]    >        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native 
Method)
   [junit4]    >        at 
jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 1034590 INFO  
(SUITE-TestReplicationHandler-seed#[B06F9660B0CAAE5E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_B06F9660B0CAAE5E-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{name=BlockTreeOrds(blocksize=128), id=BlockTreeOrds(blocksize=128), 
newname=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1515, 
maxMBSortInHeap=5.517587697582466, sim=ClassicSimilarity, locale=is, 
timezone=Asia/Omsk
   [junit4]   2> NOTE: Linux 4.4.0-42-generic i386/Oracle Corporation 9-ea 
(32-bit)/cpus=12,threads=1,free=442761096,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [SolrCloudExampleTest, 
DeleteNodeTest, SparseHLLTest, AlternateDirectoryTest, CSVRequestHandlerTest, 
TestCorePropertiesReload, RuleEngineTest, TestPostingsSolrHighlighter, 
TestSchemaSimilarityResource, HdfsWriteToMultipleCollectionsTest, 
ZkStateReaderTest, TestFieldCache, ShardRoutingCustomTest, 
DistributedMLTComponentTest, TestBulkSchemaConcurrent, BooleanFieldTest, 
TestRequestStatusCollectionAPI, TestConfigSetsAPIZkFailure, TestNumericTerms64, 
MoreLikeThisHandlerTest, AnalyticsQueryTest, IndexSchemaTest, 
CollectionsAPIAsyncDistributedZkTest, TestDistribDocBasedVersion, 
AtomicUpdatesTest, TestBulkSchemaAPI, TestClassicSimilarityFactory, 
DocumentBuilderTest, JsonLoaderTest, TestRuleBasedAuthorizationPlugin, 
TestImplicitCoreProperties, TermVectorComponentTest, TestSimpleQParserPlugin, 
ConvertedLegacyTest, TestHashPartitioner, TestManagedResource, 
TestSolrQueryResponse, TestRandomCollapseQParserPlugin, 
TestManagedStopFilterFactory, HdfsCollectionsAPIDistributedZkTest, 
TestDefaultSearchFieldResource, LeaderInitiatedRecoveryOnShardRestartTest, 
RankQueryTest, DistributedIntervalFacetingTest, 
TestSolrCloudWithSecureImpersonation, DocValuesTest, ConfigSetsAPITest, 
TestCollectionAPI, SaslZkACLProviderTest, FileUtilsTest, 
ChaosMonkeyNothingIsSafeTest, CustomCollectionTest, SpellingQueryConverterTest, 
TestSweetSpotSimilarityFactory, OverseerTaskQueueTest, 
TestGraphMLResponseWriter, TestElisionMultitermQuery, 
DistribJoinFromCollectionTest, CdcrReplicationHandlerTest, 
TestPerFieldSimilarity, DistributedExpandComponentTest, LoggingHandlerTest, 
BaseCdcrDistributedZkTest, PreAnalyzedUpdateProcessorTest, 
TestPartialUpdateDeduplication, SoftAutoCommitTest, TestRecoveryHdfs, 
TestNRTOpen, WordBreakSolrSpellCheckerTest, WrapperMergePolicyFactoryTest, 
DirectSolrSpellCheckerTest, QueryParsingTest, TestSubQueryTransformer, 
ForceLeaderTest, ZkSolrClientTest, BasicFunctionalityTest, 
SolrCmdDistributorTest, TestOnReconnectListenerSupport, 
UpdateRequestProcessorFactoryTest, DistributedFacetPivotWhiteBoxTest, 
TestReplicationHandlerBackup, TestFiltering, HdfsRecoveryZkTest, 
TestPerFieldSimilarityWithDefaultOverride, 
DocExpirationUpdateProcessorFactoryTest, SimplePostToolTest, 
AliasIntegrationTest, TestSolrQueryParserDefaultOperatorResource, 
TestConfigSetImmutable, TestTolerantUpdateProcessorCloud, ShardRoutingTest, 
TestJettySolrRunner, DistributedSuggestComponentTest, ZkControllerTest, 
DirectSolrConnectionTest, SpatialFilterTest, BlockCacheTest, 
SuggestComponentContextFilterQueryTest, BigEndianAscendingWordSerializerTest, 
FullHLLTest, CollectionReloadTest, TestBM25SimilarityFactory, TestCharFilters, 
TestReplicationHandler]
   [junit4] Completed [313/647 (1!)] on J1 in 70.28s, 15 tests, 1 failure, 1 
skipped <<< FAILURES!

[...truncated 50980 lines...]

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

Reply via email to