Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.4-Linux/69/
Java: 32bit/jdk1.8.0_121 -client -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException  at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
  at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
  at 
org.apache.solr.core.MetricsDirectoryFactory.get(MetricsDirectoryFactory.java:201)
  at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:369)  
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251) 
 at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) 
 at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
  at java.lang.Thread.run(Thread.java:745)  

Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not 
released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
        at 
org.apache.solr.core.MetricsDirectoryFactory.get(MetricsDirectoryFactory.java:201)
        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:369)
        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
        at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
        at java.lang.Thread.run(Thread.java:745)


        at __randomizedtesting.SeedInfo.seed([579154E17036CA48]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:269)
        at sun.reflect.GeneratedMethodAccessor47.invoke(Unknown Source)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 11788 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/init-core-data-001
   [junit4]   2> 993470 INFO  
(SUITE-TestReplicationHandler-seed#[579154E17036CA48]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 993471 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
   [junit4]   2> 993472 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/collection1
   [junit4]   2> 993478 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 993479 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@bc072f{/solr,null,AVAILABLE}
   [junit4]   2> 993480 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@d48d10{HTTP/1.1,[http/1.1]}{127.0.0.1:37546}
   [junit4]   2> 993480 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server Started @996246ms
   [junit4]   2> 993480 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=37546}
   [junit4]   2> 993480 ERROR 
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 993481 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.4.1
   [junit4]   2> 993481 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 993481 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 993481 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-29T07:08:20.887Z
   [junit4]   2> 993481 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/solr.xml
   [junit4]   2> 993489 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 993493 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/.
   [junit4]   2> 993493 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 993502 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.1
   [junit4]   2> 993511 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 993514 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 993515 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/./collection1
   [junit4]   2> 993515 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 993517 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.6630859375, 
floorSegmentMB=1.279296875, forceMergeDeletesPctAllowed=11.852602874499965, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7419466956245903
   [junit4]   2> 993528 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 993528 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 993529 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7003237966882058]
   [junit4]   2> 993529 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e114a7[collection1] 
main]
   [junit4]   2> 993530 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-001/collection1/conf
   [junit4]   2> 993530 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 993530 INFO  (coreLoadExecutor-4378-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 993530 INFO  
(searcherExecutor-4379-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e114a7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 993981 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/collection1
   [junit4]   2> 993983 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 993984 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@18ba15{/solr,null,AVAILABLE}
   [junit4]   2> 993985 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@160eca6{HTTP/1.1,[http/1.1]}{127.0.0.1:44515}
   [junit4]   2> 993985 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server Started @996750ms
   [junit4]   2> 993985 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/collection1/data,
 hostContext=/solr, hostPort=44515}
   [junit4]   2> 993985 ERROR 
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 993985 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.4.1
   [junit4]   2> 993985 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 993985 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 993986 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-29T07:08:21.392Z
   [junit4]   2> 993986 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/solr.xml
   [junit4]   2> 993991 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 993995 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/.
   [junit4]   2> 993995 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 994006 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.1
   [junit4]   2> 994014 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 994016 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 994017 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/./collection1
   [junit4]   2> 994018 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 994019 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.6630859375, 
floorSegmentMB=1.279296875, forceMergeDeletesPctAllowed=11.852602874499965, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7419466956245903
   [junit4]   2> 994030 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 994030 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 994030 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7003237966882058]
   [junit4]   2> 994031 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@26db4d[collection1] 
main]
   [junit4]   2> 994031 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-002/collection1/conf
   [junit4]   2> 994033 INFO  (coreLoadExecutor-4388-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 994033 INFO  
(searcherExecutor-4389-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26db4d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 994347 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 994347 INFO  (indexFetcher-4393-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 994347 INFO  (indexFetcher-4393-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 994347 INFO  (indexFetcher-4393-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 994479 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 994483 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 1
   [junit4]   2> 994486 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 994486 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1825fdf
   [junit4]   2> 994491 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1c43c2d[collection1] main]
   [junit4]   2> 994491 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 994491 INFO  
(searcherExecutor-4379-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1c43c2d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)))}
   [junit4]   2> 994491 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 5
   [junit4]   2> 994505 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 994508 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 994509 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 994509 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485673701892
   [junit4]   2> 994509 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 994509 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 994513 INFO  (qtp15983528-13805) [    x:collection1] 
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=2
   [junit4]   2> 994513 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 994514 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=35, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=61.2900390625, 
floorSegmentMB=0.734375, forceMergeDeletesPctAllowed=11.429336492423902, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3423310334467072
   [junit4]   2> 994516 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 994516 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1ca2808 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c67d79))
   [junit4]   2> 994518 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994522 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994526 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994529 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994532 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994535 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994538 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994541 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994544 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994552 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994552 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1757) : 0 secs (null bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1ca2808 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c67d79))
   [junit4]   2> 994554 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=97.001953125, 
floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=5.7055048998895455, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 994556 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 994557 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@88bb41[collection1] main]
   [junit4]   2> 994557 INFO  
(searcherExecutor-4389-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@88bb41[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)))}
   [junit4]   2> 994557 INFO  (qtp16789261-13820) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:37546/solr/collection1/replication&command=fetchindex}
 status=0 QTime=53
   [junit4]   2> 994563 INFO  (qtp16789261-13822) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 994564 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 994567 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 994569 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 994576 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 994577 INFO  (qtp16789261-13815) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=4
   [junit4]   2> 994579 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 994583 INFO  (qtp16789261-13820) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 994585 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 994586 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 994586 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 994586 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 994587 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 994590 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 994590 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 994591 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485673701892
   [junit4]   2> 994591 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 994591 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 994591 INFO  (qtp16789261-13821) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:37546/solr/collection1/replication&command=fetchindex}
 status=0 QTime=3
   [junit4]   2> 994595 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 1
   [junit4]   2> 994597 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 994597 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1825fdf
   [junit4]   2> 994602 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@8b015a[collection1] main]
   [junit4]   2> 994602 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 994602 INFO  
(searcherExecutor-4379-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@8b015a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)
 Uninverting(_1(6.4.1):C1)))}
   [junit4]   2> 994602 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 5
   [junit4]   2> 994604 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 994607 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 994607 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 994607 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485673702004
   [junit4]   2> 994607 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 994607 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 994611 INFO  (qtp15983528-13803) [    x:collection1] 
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=2
   [junit4]   2> 994612 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 19
   [junit4]   2> 994614 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=35, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=61.2900390625, 
floorSegmentMB=0.734375, forceMergeDeletesPctAllowed=11.429336492423902, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3423310334467072
   [junit4]   2> 994615 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 994615 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@19627b3 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10663ae))
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib 
because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv 
because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc 
because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 994616 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 994619 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994621 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994624 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994627 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994630 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994633 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994635 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994638 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994641 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994644 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 994645 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1820) : 0 secs (null bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@19627b3 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10663ae))
   [junit4]   2> 994647 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=97.001953125, 
floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=5.7055048998895455, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 994648 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 994650 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@73fcde[collection1] main]
   [junit4]   2> 994650 INFO  
(searcherExecutor-4389-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@73fcde[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)
 Uninverting(_1(6.4.1):C1)))}
   [junit4]   2> 994650 INFO  (qtp16789261-13822) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:37546/solr/collection1/replication&command=fetchindex}
 status=0 QTime=46
   [junit4]   2> 994655 INFO  (qtp16789261-13816) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=1
   [junit4]   2> 994655 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 994657 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 994660 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 994665 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 994666 INFO  (qtp16789261-13820) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=3
   [junit4]   2> 994668 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 994673 INFO  (qtp16789261-13821) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 994676 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 994679 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 994679 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1825fdf
   [junit4]   2> 994682 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1c71390[collection1] realtime]
   [junit4]   2> 994682 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 994683 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 4
   [junit4]   2> 994684 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 994687 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 994687 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 994687 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485673702085
   [junit4]   2> 994687 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 994687 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 994694 INFO  (qtp15983528-13805) [    x:collection1] 
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=4
   [junit4]   2> 994695 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 28
   [junit4]   2> 994696 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=35, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=61.2900390625, 
floorSegmentMB=0.734375, forceMergeDeletesPctAllowed=11.429336492423902, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3423310334467072
   [junit4]   2> 994697 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 994698 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 994698 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 995698 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 995698 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 996699 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 996699 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 997700 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 997700 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 998701 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 998701 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 999701 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 999702 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1000703 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1000703 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1001704 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1001704 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1002705 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1002705 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1003705 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1003706 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1004706 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Found unused file: segments_2
   [junit4]   2> 1004706 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files 
to be delete-able
   [junit4]   2> 1005707 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index 
files to be delete-able
   [junit4]   2> 1005707 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@19079cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1307954))
   [junit4]   2> 1005707 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib 
because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv 
because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc 
because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 1005708 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.doc 
because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tib 
because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
   [junit4]   2> 1005709 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tiv 
because it already exists
   [junit4]   2> 1005712 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005715 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005718 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005721 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005724 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005726 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005729 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005732 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005734 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005737 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005738 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1883) : 11 secs (171 bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@19079cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1307954))
   [junit4]   2> 1005739 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=97.001953125, 
floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=5.7055048998895455, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1005741 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1005744 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@11799cb[collection1] main]
   [junit4]   2> 1005744 INFO  
(searcherExecutor-4389-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11799cb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)
 Uninverting(_1(6.4.1):C1) Uninverting(_2(6.4.1):C1)))}
   [junit4]   2> 1005744 INFO  (qtp16789261-13815) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:37546/solr/collection1/replication&command=fetchindex}
 status=0 QTime=11060
   [junit4]   2> 1005748 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1005749 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
   [junit4]   2> 1005751 INFO  (qtp16789261-13822) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 1005752 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
   [junit4]   2> 1005755 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 1005757 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1005757 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@1825fdf
   [junit4]   2> 1005761 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@68c31a[collection1] main]
   [junit4]   2> 1005761 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1005761 INFO  
(searcherExecutor-4379-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@68c31a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)
 Uninverting(_1(6.4.1):C1) Uninverting(_2(6.4.1):C1) 
Uninverting(_3(6.4.1):C1)))}
   [junit4]   2> 1005761 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={commit=true&wt=javabin&version=2}{commit=} 0 4
   [junit4]   2> 1005763 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1005766 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1005767 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 5
   [junit4]   2> 1005767 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485673713163
   [junit4]   2> 1005767 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 4
   [junit4]   2> 1005767 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1005770 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&wt=javabin&version=2&command=filelist} 
status=0 QTime=1
   [junit4]   2> 1005771 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 37
   [junit4]   2> 1005773 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=35, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=61.2900390625, 
floorSegmentMB=0.734375, forceMergeDeletesPctAllowed=11.429336492423902, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3423310334467072
   [junit4]   2> 1005775 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1005775 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1354058 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19c8487))
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tib 
because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.tiv 
because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_LuceneVarGapFixedInterval_0.doc 
because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.doc 
because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tib 
because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
   [junit4]   2> 1005776 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_LuceneVarGapFixedInterval_0.tiv 
because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.nvd because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2_LuceneVarGapFixedInterval_0.tib 
because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.fdt because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2_LuceneVarGapFixedInterval_0.tiv 
because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.fnm because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.nvm because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2.fdx because it already exists
   [junit4]   2> 1005777 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _2_LuceneVarGapFixedInterval_0.doc 
because it already exists
   [junit4]   2> 1005781 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fdx&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005783 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005786 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005789 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.nvd&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005792 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005798 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fnm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005801 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.nvm&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005804 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3.fdt&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005807 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.tib&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005810 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1005810 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1946) : 0 secs (null bytes/sec) to 
MetricsDirectory(MockDirectoryWrapper(RAMDirectory@1354058 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19c8487))
   [junit4]   2> 1005812 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=34, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=97.001953125, 
floorSegmentMB=0.21484375, forceMergeDeletesPctAllowed=5.7055048998895455, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1005815 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1005817 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14d6341[collection1] main]
   [junit4]   2> 1005818 INFO  
(searcherExecutor-4389-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14d6341[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)
 Uninverting(_1(6.4.1):C1) Uninverting(_2(6.4.1):C1) 
Uninverting(_3(6.4.1):C1)))}
   [junit4]   2> 1005818 INFO  (qtp16789261-13816) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:37546/solr/collection1/replication&command=fetchindex}
 status=0 QTime=55
   [junit4]   2> 1005822 INFO  (qtp15983528-13803) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} 
hits=4 status=0 QTime=1
   [junit4]   2> 1005823 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 1005826 INFO  (qtp16789261-13820) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} 
hits=4 status=0 QTime=1
   [junit4]   2> 1005827 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
   [junit4]   2> 1005830 INFO  (qtp15983528-13798) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 1005832 INFO  (qtp15983528-13805) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 1005837 INFO  (qtp15983528-13804) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 1005839 INFO  (qtp16789261-13815) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=4
   [junit4]   2> 1005841 INFO  (qtp15983528-13799) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1005844 INFO  (qtp16789261-13822) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion}
 status=0 QTime=0
   [junit4]   2> 1005844 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
   [junit4]   2> 1005845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@d48d10{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1005845 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1266968
   [junit4]   2> 1005845 INFO  (coreCloseExecutor-4398-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3f89bf
   [junit4]   2> 1005846 INFO  (coreCloseExecutor-4398-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1005847 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1005847 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@bc072f{/solr,null,UNAVAILABLE}
   [junit4]   2> 1005848 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@160eca6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1005848 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=4869793
   [junit4]   2> 1005848 INFO  (coreCloseExecutor-4400-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@bef165
   [junit4]   2> 1005851 INFO  (coreCloseExecutor-4400-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1005851 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1005851 INFO  
(TEST-TestReplicationHandler.testEmptyCommits-seed#[579154E17036CA48]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@18ba15{/solr,null,UNAVAILABLE}
   [junit4]   2> 1005854 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting doTestDetails
   [junit4]   2> 1005854 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/collection1
   [junit4]   2> 1005857 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1005858 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@c3dc50{/solr,null,AVAILABLE}
   [junit4]   2> 1005858 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1ab3d00{HTTP/1.1,[http/1.1]}{127.0.0.1:45450}
   [junit4]   2> 1005858 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server Started @1008624ms
   [junit4]   2> 1005858 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/collection1/data,
 hostContext=/solr, hostPort=45450}
   [junit4]   2> 1005859 ERROR 
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1005859 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.4.1
   [junit4]   2> 1005859 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1005859 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1005859 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-29T07:08:33.265Z
   [junit4]   2> 1005859 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/solr.xml
   [junit4]   2> 1005867 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1005871 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/.
   [junit4]   2> 1005871 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1005881 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.1
   [junit4]   2> 1005890 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1005893 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1005894 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/./collection1
   [junit4]   2> 1005894 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/./collection1/data/]
   [junit4]   2> 1005896 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.6630859375, 
floorSegmentMB=1.279296875, forceMergeDeletesPctAllowed=11.852602874499965, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7419466956245903
   [junit4]   2> 1005907 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1005907 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1005907 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7003237966882058]
   [junit4]   2> 1005908 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ed366[collection1] 
main]
   [junit4]   2> 1005908 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-003/collection1/conf
   [junit4]   2> 1005908 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1005908 INFO  (coreLoadExecutor-4407-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1005909 INFO  
(searcherExecutor-4408-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7ed366[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1006373 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/collection1
   [junit4]   2> 1006376 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 1006377 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@163625{/solr,null,AVAILABLE}
   [junit4]   2> 1006378 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@cf2e68{HTTP/1.1,[http/1.1]}{127.0.0.1:45468}
   [junit4]   2> 1006378 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.e.j.s.Server Started @1009144ms
   [junit4]   2> 1006378 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/collection1/data,
 hostContext=/solr, hostPort=45468}
   [junit4]   2> 1006378 ERROR 
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1006379 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.4.1
   [junit4]   2> 1006379 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1006379 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1006379 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-01-29T07:08:33.785Z
   [junit4]   2> 1006379 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/solr.xml
   [junit4]   2> 1006387 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 1006391 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/.
   [junit4]   2> 1006391 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[579154E17036CA48]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1006406 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.1
   [junit4]   2> 1006419 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1006422 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 1006423 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/./collection1
   [junit4]   2> 1006424 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/./collection1/data/]
   [junit4]   2> 1006426 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.6630859375, 
floorSegmentMB=1.279296875, forceMergeDeletesPctAllowed=11.852602874499965, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7419466956245903
   [junit4]   2> 1006444 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1006444 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1006444 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7003237966882058]
   [junit4]   2> 1006445 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@d3807[collection1] 
main]
   [junit4]   2> 1006446 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-004/collection1/conf
   [junit4]   2> 1006455 INFO  (coreLoadExecutor-4417-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 1006456 INFO  
(searcherExecutor-4418-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@d3807[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1006929 INFO  (qtp24905545-13855) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 1006932 INFO  (qtp24905545-13856) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 1006936 INFO  (qtp24905545-13850) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={slave=false&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=0
   [junit4]   2> 1006937 INFO  (qtp19014197-13872) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=2
   [junit4]   2> 1006940 INFO  (qtp24905545-13857) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[0]} 0 1
   [junit4]   2> 1006943 INFO  (qtp24905545-13856) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1006943 INFO  (qtp24905545-13856) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@a53aaa
   [junit4]   2> 1006946 INFO  (qtp24905545-13856) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14199ee[collection1] main]
   [junit4]   2> 1006947 INFO  (qtp24905545-13856) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1006947 INFO  
(searcherExecutor-4408-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14199ee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.1):C1)))}
   [junit4]   2> 1006947 INFO  (qtp24905545-13856) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 4
   [junit4]   2> 1006949 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1006951 INFO  (qtp24905545-13855) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1006952 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1006952 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1485673714349
   [junit4]   2> 1006952 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1006952 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1006955 INFO  (qtp24905545-13850) [    x:collection1] 
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=1
   [junit4]   2> 1006956 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 1006957 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.

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

ene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, 
maxMergeAtOnceExplicit=28, maxMergedSegmentMB=9.6630859375, 
floorSegmentMB=1.279296875, forceMergeDeletesPctAllowed=11.852602874499965, 
segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7419466956245903
   [junit4]   2> 1082859 INFO  (coreLoadExecutor-5021-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1082859 INFO  (coreLoadExecutor-5021-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1082859 INFO  (coreLoadExecutor-5021-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7003237966882058]
   [junit4]   2> 1082860 INFO  (coreLoadExecutor-5021-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@590fa9[collection1] 
main]
   [junit4]   2> 1082860 INFO  (coreLoadExecutor-5021-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/home/jenkins/workspace/Lucene-Solr-6.4-Linux/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_579154E17036CA48-001/solr-instance-030/collection1/conf
   [junit4]   2> 1082861 INFO  (coreLoadExecutor-5021-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 1082862 INFO  
(searcherExecutor-5022-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@590fa9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1082863 INFO  (qtp20752929-14826) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0
   [junit4]   2> 1082863 INFO  (indexFetcher-5026-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1082864 INFO  (indexFetcher-5026-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1082864 INFO  (indexFetcher-5026-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1083311 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.a.s.SolrTestCaseJ4 ###Ending doTestHandlerPathUnchanged
   [junit4]   2> 1083312 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.e.j.s.AbstractConnector Stopped 
ServerConnector@ff6743{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1083312 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31490570
   [junit4]   2> 1083312 INFO  (coreCloseExecutor-5027-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@cb3ea3
   [junit4]   2> 1083313 INFO  (coreCloseExecutor-5027-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1083314 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1083314 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@140b2fc{/solr,null,UNAVAILABLE}
   [junit4]   2> 1083315 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.e.j.s.AbstractConnector Stopped 
ServerConnector@a3a569{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1083315 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=510723
   [junit4]   2> 1083315 INFO  (coreCloseExecutor-5029-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1e8eae2
   [junit4]   2> 1083316 INFO  (coreCloseExecutor-5029-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 1083316 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 1083316 INFO  
(TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[579154E17036CA48])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7ddb91{/solr,null,UNAVAILABLE}
   [junit4]   2> 1083318 INFO  
(SUITE-TestReplicationHandler-seed#[579154E17036CA48]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{name=PostingsFormat(name=LuceneVarGapFixedInterval), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), 
newname=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, 
maxPointsInLeafNode=1981, maxMBSortInHeap=6.142933649242391, 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=ar-LY, 
timezone=Europe/Guernsey
   [junit4]   2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0_121 
(32-bit)/cpus=12,threads=1,free=198206880,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [HdfsSyncSliceTest, 
CloneFieldUpdateProcessorFactoryTest, TestSchemaNameResource, 
SpatialRPTFieldTypeTest, TestUnifiedSolrHighlighter, PrimUtilsTest, 
TestIndexingPerformance, TestBinaryResponseWriter, TestMaxScoreQueryParser, 
HdfsDirectoryFactoryTest, RequestLoggingTest, TestClusterStateMutator, 
TestLMJelinekMercerSimilarityFactory, TestDFRSimilarityFactory, 
DocValuesMultiTest, TestFieldResource, DebugComponentTest, 
TestPhraseSuggestions, SolrCloudExampleTest, RecoveryZkTest, 
TestDefaultStatsCache, PreAnalyzedFieldTest, SolrTestCaseJ4Test, 
TlogReplayBufferedWhileIndexingTest, TestCustomSort, BlobRepositoryCloudTest, 
SuggesterWFSTTest, TemplateUpdateProcessorTest, 
TestRuleBasedAuthorizationPlugin, TestJmxMonitoredMap, 
CollectionStateFormat2Test, NoCacheHeaderTest, TestCoreDiscovery, 
CurrencyFieldXmlFileTest, SortSpecParsingTest, TestConfig, 
DistributedQueueTest, TestSmileRequest, TestSchemaManager, GraphQueryTest, 
ShowFileRequestHandlerTest, TestUtils, TestReversedWildcardFilterFactory, 
TestReload, SimpleFacetsTest, CoreAdminRequestStatusTest, TestBinaryField, 
TestRebalanceLeaders, SolrPluginUtilsTest, OverseerTest, CleanupOldIndexTest, 
TestGroupingSearch, AutoCommitTest, PingRequestHandlerTest, 
TestCloudPseudoReturnFields, TestSolrQueryParserResource, 
OpenExchangeRatesOrgProviderTest, SpatialHeatmapFacetsTest, 
XmlUpdateRequestHandlerTest, TestHdfsCloudBackupRestore, BufferStoreTest, 
TestTolerantSearch, CoreAdminHandlerTest, BinaryUpdateRequestHandlerTest, 
TestGeoJSONResponseWriter, RemoteQueryErrorTest, TestShardHandlerFactory, 
MetricUtilsTest, DistributedQueryComponentCustomSortTest, TestIndexSearcher, 
TestDistribDocBasedVersion, FieldMutatingUpdateProcessorTest, 
TestBlendedInfixSuggestions, UnloadDistributedZkTest, TestSolrQueryParser, 
ParsingFieldUpdateProcessorsTest, TestFieldCacheSanityChecker, 
PrimitiveFieldTypeTest, StatsReloadRaceTest, TestTrackingShardHandlerFactory, 
TestSubQueryTransformerCrossCore, TestExtendedDismaxParser, 
TestImpersonationWithHadoopAuth, TestNumericTerms32, TestJsonFacetRefinement, 
UUIDUpdateProcessorFallbackTest, SolrMetricsIntegrationTest, 
TestClassicSimilarityFactory, TestManagedResourceStorage, 
FieldAnalysisRequestHandlerTest, BadIndexSchemaTest, TestWriterPerf, 
TestStressLiveNodes, TestDynamicLoading, DOMUtilTest, 
TestDistributedMissingSort, TestHighFrequencyDictionaryFactory, 
SolrCoreCheckLockOnStartupTest, TestShortCircuitedRequests, TestXIncludeConfig, 
TestLeaderInitiatedRecoveryThread, QueryParsingTest, 
TestRequestStatusCollectionAPI, RestartWhileUpdatingTest, 
TestPerFieldSimilarityClassic, BasicDistributedZkTest, 
LeaderElectionIntegrationTest, TestReplicationHandler]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandler -Dtests.seed=579154E17036CA48 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-LY 
-Dtests.timezone=Europe/Guernsey -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J2 | TestReplicationHandler (suite) <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 
object(s) that were not released!!! [NRTCachingDirectory]
   [junit4]    > 
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
   [junit4]    >        at 
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
   [junit4]    >        at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
   [junit4]    >        at 
org.apache.solr.core.MetricsDirectoryFactory.get(MetricsDirectoryFactory.java:201)
   [junit4]    >        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:369)
   [junit4]    >        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
   [junit4]    >        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
   [junit4]    >        at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([579154E17036CA48]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:269)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4] Completed [304/680 (1!)] on J2 in 120.97s, 15 tests, 1 failure, 1 
skipped <<< FAILURES!

[...truncated 55855 lines...]

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

Reply via email to