Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1395/

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

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

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([2562BB911BE0EA96:D21155C9DD084570]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1329)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        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 11571 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/init-core-data-001
   [junit4]   2> 1704011 INFO  
(SUITE-TestReplicationHandler-seed#[2562BB911BE0EA96]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 1704013 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting doTestDetails
   [junit4]   2> 1704013 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1
   [junit4]   2> 1704028 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1704223 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@54e71eb3{/solr,null,AVAILABLE}
   [junit4]   2> 1704224 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@651d0ab7{HTTP/1.1,[http/1.1]}{127.0.0.1:50490}
   [junit4]   2> 1704225 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.Server Started @1711176ms
   [junit4]   2> 1704225 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=50490}
   [junit4]   2> 1704225 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 1704225 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001'
   [junit4]   2> 1704225 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1704225 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1704240 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/solr.xml
   [junit4]   2> 1704245 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/.
   [junit4]   2> 1704245 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 711466950
   [junit4]   2> 1704245 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001]
   [junit4]   2> 1704245 WARN  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/lib
   [junit4]   2> 1704245 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,
   [junit4]   2> 1704258 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
   [junit4]   2> 1704258 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1704258 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1704258 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1704258 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1704259 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/.
   [junit4]   2> 1704260 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, 
configSetProperties=configsetprops.json, transient=false, dataDir=data/}
   [junit4]   2> 1704260 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1
   [junit4]   2> 1704260 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1704261 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1'
   [junit4]   2> 1704271 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1704271 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1704266 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2
   [junit4]   2> 1704274 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1704281 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1704283 WARN  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1704284 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1704290 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1704292 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1704299 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1704301 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1'
   [junit4]   2> 1704301 INFO  (coreLoadExecutor-4221-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1
   [junit4]   2> 1704301 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1704301 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 1704301 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 1704302 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data/snapshot_metadata
   [junit4]   2> 1704302 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot 
metadata file...
   [junit4]   2> 1704302 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data
   [junit4]   2> 1704302 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data/index/
   [junit4]   2> 1704302 WARN  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1704302 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data/index
   [junit4]   2> 1704302 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11435327799234081]
   [junit4]   2> 1704303 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_1,generation=1}
   [junit4]   2> 1704303 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1704305 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1704306 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704307 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704308 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704308 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704310 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 1704310 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
   [junit4]   2> 1704310 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1704310 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/./collection1/data/
   [junit4]   2> 1704310 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1704310 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=41, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=46.5107421875, 
floorSegmentMB=0.5888671875, forceMergeDeletesPctAllowed=19.076221152924493, 
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8454218555594214
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_1,generation=1}
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@598bcdd7[collection1] main]
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1/conf
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1/conf}
   [junit4]   2> 1704311 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-001/collection1/conf
   [junit4]   2> 1704312 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1704312 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1704312 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1704312 INFO  (coreLoadExecutor-4221-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1704312 INFO  
(searcherExecutor-4222-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@598bcdd7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1704776 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1
   [junit4]   2> 1704779 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1704780 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@4ca46f78{/solr,null,AVAILABLE}
   [junit4]   2> 1704781 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@36c15d0{HTTP/1.1,[http/1.1]}{127.0.0.1:50286}
   [junit4]   2> 1704781 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.Server Started @1711732ms
   [junit4]   2> 1704781 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1/data,
 hostContext=/solr, hostPort=50286}
   [junit4]   2> 1704781 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 1704781 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002'
   [junit4]   2> 1704782 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1704782 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1704782 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/solr.xml
   [junit4]   2> 1704788 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/.
   [junit4]   2> 1704788 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1232149578
   [junit4]   2> 1704788 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002]
   [junit4]   2> 1704789 WARN  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/lib
   [junit4]   2> 1704794 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,
   [junit4]   2> 1704823 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
   [junit4]   2> 1704823 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1704823 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1704824 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1704824 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1704824 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/.
   [junit4]   2> 1704825 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, 
configSetProperties=configsetprops.json, transient=false, dataDir=data/}
   [junit4]   2> 1704825 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1
   [junit4]   2> 1704825 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 1704826 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1'
   [junit4]   2> 1704830 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1704830 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1704827 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2
   [junit4]   2> 1704835 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 1704838 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1704841 WARN  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use 
<mergePolicyFactory> instead.
   [junit4]   2> 1704841 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 1704847 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1704903 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1704921 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1704922 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1'
   [junit4]   2> 1704922 INFO  (coreLoadExecutor-4231-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1
   [junit4]   2> 1704922 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1704922 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 1704923 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 1704923 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/snapshot_metadata
   [junit4]   2> 1704923 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.s.SolrSnapshotMetaDataManager Loading from snapshot 
metadata file...
   [junit4]   2> 1704923 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data
   [junit4]   2> 1704923 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index/
   [junit4]   2> 1704923 WARN  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1704924 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index
   [junit4]   2> 1704924 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11435327799234081]
   [junit4]   2> 1704926 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_1,generation=1}
   [junit4]   2> 1704926 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1704943 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 1704945 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704945 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704946 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704947 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1704948 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 1704948 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
   [junit4]   2> 1704948 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1704948 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/
   [junit4]   2> 1704954 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1704954 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1704954 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=41, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=46.5107421875, 
floorSegmentMB=0.5888671875, forceMergeDeletesPctAllowed=19.076221152924493, 
segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8454218555594214
   [junit4]   2> 1704962 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_1,generation=1}
   [junit4]   2> 1704962 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1704962 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@645d4afa[collection1] main]
   [junit4]   2> 1704962 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1/conf
   [junit4]   2> 1704963 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1/conf}
   [junit4]   2> 1704963 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/collection1/conf
   [junit4]   2> 1704963 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1704986 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 1704986 INFO  (coreLoadExecutor-4231-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1704986 INFO  
(searcherExecutor-4232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@645d4afa[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1705006 INFO  (qtp1341691261-9906) [    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> 1705006 INFO  (indexFetcher-4236-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 1705006 INFO  (indexFetcher-4236-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 1705006 INFO  (indexFetcher-4236-thread-1) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1705340 INFO  (qtp1341691261-9904) [    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> 1705353 INFO  (qtp1341691261-9904) [    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=12
   [junit4]   2> 1705357 INFO  (qtp1341691261-9906) [    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> 1705357 INFO  (qtp34205198-9923) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} 
status=0 QTime=1
   [junit4]   2> 1705364 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[0]} 0 1
   [junit4]   2> 1705365 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1705365 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@61250a1e
   [junit4]   2> 1705366 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_2,generation=2}
   [junit4]   2> 1705366 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1705367 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3518fcd5[collection1] main]
   [junit4]   2> 1705367 INFO  
(searcherExecutor-4222-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3518fcd5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 1705367 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1705368 INFO  (qtp1341691261-9909) [    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 2
   [junit4]   2> 1705373 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1705375 INFO  (qtp1341691261-9906) [    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> 1705375 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 2
   [junit4]   2> 1705375 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1474191513552
   [junit4]   2> 1705375 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 1705375 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1705378 INFO  (qtp1341691261-9906) [    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> 1705378 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 10
   [junit4]   2> 1705379 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053833565
   [junit4]   2> 1705380 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 1705380 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=19.5830078125, 
floorSegmentMB=0.9638671875, forceMergeDeletesPctAllowed=0.6468382472342571, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8875894548647594
   [junit4]   2> 1705381 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_1,generation=1}
   [junit4]   2> 1705381 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1705381 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1705381 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_1,generation=1}
   [junit4]   2> 1705381 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 1705382 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 1705382 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@5fa678e9 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@45b9ed1c)
   [junit4]   2> 1705382 INFO  (qtp1341691261-9906) [    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> 1705386 INFO  (qtp1341691261-9907) [    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> 1705393 INFO  (qtp1341691261-9908) [    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> 1705394 INFO  (qtp1341691261-9908) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1705397 INFO  (qtp1341691261-9908) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1705411 INFO  (qtp1341691261-9904) [    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> 1705417 INFO  (qtp1341691261-9904) [    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> 1705421 INFO  (qtp1341691261-9904) [    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> 1705422 INFO  (qtp1341691261-9906) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=2&qt=/replication&file=_0_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1705423 INFO  (qtp1341691261-9907) [    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> 1705425 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1696) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@5fa678e9 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@45b9ed1c)
   [junit4]   2> 1705428 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=37, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=24.115234375, 
floorSegmentMB=2.029296875, forceMergeDeletesPctAllowed=10.98553308996783, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4273648615227591
   [junit4]   2> 1705429 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_2,generation=2}
   [junit4]   2> 1705430 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1705430 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1705431 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3fab3d37[collection1] main]
   [junit4]   2> 1705431 INFO  
(searcherExecutor-4232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3fab3d37[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)))}
   [junit4]   2> 1705432 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053833565
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053833565;done=true>>]
   [junit4]   2> 1705432 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=false
   [junit4]   2> 1705432 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053833565
   [junit4]   2> 1705432 INFO  (qtp34205198-9921) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:50490/solr/collection1/replication&command=fetchindex}
 status=0 QTime=63
   [junit4]   2> 1705475 INFO  (qtp1341691261-9908) [    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> 1705476 INFO  (qtp34205198-9924) [    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=37
   [junit4]   2> 1705477 INFO  (qtp1341691261-9909) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 1705477 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1705478 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@61250a1e
   [junit4]   2> 1705486 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_3,generation=3}
   [junit4]   2> 1705486 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1705486 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@417bf53b[collection1] main]
   [junit4]   2> 1705486 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1705487 INFO  
(searcherExecutor-4222-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@417bf53b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1)))}
   [junit4]   2> 1705487 INFO  (qtp1341691261-9903) [    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 9
   [junit4]   2> 1705494 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1705506 INFO  (qtp1341691261-9910) [    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> 1705507 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 3
   [junit4]   2> 1705507 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1474191513665
   [junit4]   2> 1705507 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 2
   [junit4]   2> 1705507 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1705558 INFO  (qtp1341691261-9910) [    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=50
   [junit4]   2> 1705559 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 19
   [junit4]   2> 1705858 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834045
   [junit4]   2> 1705874 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 1705874 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=19.5830078125, 
floorSegmentMB=0.9638671875, forceMergeDeletesPctAllowed=0.6468382472342571, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8875894548647594
   [junit4]   2> 1705875 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_2,generation=2}
   [junit4]   2> 1705875 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1705875 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1705886 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_2,generation=2}
   [junit4]   2> 1705886 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@3e5e1c70 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@797f23bf)
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tio because it 
already exists
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.doc because it 
already exists
   [junit4]   2> 1705890 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 1705891 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 1705891 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 1705891 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tipo because it 
already exists
   [junit4]   2> 1705891 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1705974 INFO  (qtp1341691261-9908) [    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> 1705976 INFO  (qtp1341691261-9909) [    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> 1705983 INFO  (qtp1341691261-9903) [    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> 1705984 INFO  (qtp1341691261-9903) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1705985 INFO  (qtp1341691261-9910) [    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> 1705986 INFO  (qtp1341691261-9907) [    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> 1705987 INFO  (qtp1341691261-9908) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=3&qt=/replication&file=_1_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1705989 INFO  (qtp1341691261-9909) [    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> 1705990 INFO  (qtp1341691261-9906) [    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> 1705990 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1759) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@3e5e1c70 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@797f23bf)
   [junit4]   2> 1705992 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Exception while updating statistics
   [junit4]   2> java.io.IOException: file "replication.properties" was already 
written to
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:697)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:509)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1705994 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=37, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=24.115234375, 
floorSegmentMB=2.029296875, forceMergeDeletesPctAllowed=10.98553308996783, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4273648615227591
   [junit4]   2> 1706000 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_3,generation=3}
   [junit4]   2> 1706000 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1706000 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1706013 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7e46d8f2[collection1] main]
   [junit4]   2> 1706013 INFO  
(searcherExecutor-4232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7e46d8f2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1)))}
   [junit4]   2> 1706014 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834045
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834045;done=true>>]
   [junit4]   2> 1706014 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=false
   [junit4]   2> 1706014 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834045
   [junit4]   2> 1706022 INFO  (qtp34205198-9926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:50490/solr/collection1/replication&command=fetchindex}
 status=0 QTime=530
   [junit4]   2> 1706038 INFO  (qtp1341691261-9903) [    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=3
   [junit4]   2> 1706038 INFO  (qtp34205198-9927) [    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=15
   [junit4]   2> 1706040 INFO  (qtp1341691261-9904) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 1
   [junit4]   2> 1706041 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1706041 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@61250a1e
   [junit4]   2> 1706043 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=4
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@75de0b08 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4b593189),segFN=segments_4,generation=4}
   [junit4]   2> 1706044 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 4
   [junit4]   2> 1706044 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4510969a[collection1] main]
   [junit4]   2> 1706044 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1706044 INFO  
(searcherExecutor-4222-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4510969a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 1706044 INFO  (qtp1341691261-9907) [    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 3
   [junit4]   2> 1706051 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication 
suffix
   [junit4]   2> 1706055 INFO  (qtp1341691261-9910) [    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> 1706055 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's generation: 4
   [junit4]   2> 1706055 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Master's version: 1474191514228
   [junit4]   2> 1706055 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Slave's generation: 3
   [junit4]   2> 1706055 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting replication process
   [junit4]   2> 1706063 INFO  (qtp1341691261-9910) [    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=1
   [junit4]   2> 1706066 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Number of files in latest index in master: 28
   [junit4]   2> 1706069 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834254
   [junit4]   2> 1706078 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 1706079 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=19.5830078125, 
floorSegmentMB=0.9638671875, forceMergeDeletesPctAllowed=0.6468382472342571, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8875894548647594
   [junit4]   2> 1706079 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_3,generation=3}
   [junit4]   2> 1706079 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1706079 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1706079 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_3,generation=3}
   [junit4]   2> 1706079 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to 
MockDirectoryWrapper(RAMDirectory@76603a0a 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5738bd56)
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tio because it 
already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.doc because it 
already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _0_BlockTreeOrds_0.tipo because it 
already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_BlockTreeOrds_0.tipo because it 
already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
   [junit4]   2> 1706080 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists
   [junit4]   2> 1706086 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists
   [junit4]   2> 1706086 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_BlockTreeOrds_0.doc because it 
already exists
   [junit4]   2> 1706087 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists
   [junit4]   2> 1706087 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists
   [junit4]   2> 1706088 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1_BlockTreeOrds_0.tio because it 
already exists
   [junit4]   2> 1706089 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists
   [junit4]   2> 1706091 INFO  (qtp1341691261-9906) [    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> 1706115 INFO  (qtp1341691261-9906) [    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> 1706116 INFO  (qtp1341691261-9904) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_BlockTreeOrds_0.doc&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1706117 INFO  (qtp1341691261-9907) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_BlockTreeOrds_0.tio&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1706119 INFO  (qtp1341691261-9909) [    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> 1706120 INFO  (qtp1341691261-9910) [    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> 1706120 INFO  (qtp1341691261-9910) [    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> 1706132 INFO  (qtp1341691261-9910) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={generation=4&qt=/replication&file=_2_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent}
 status=0 QTime=0
   [junit4]   2> 1706133 INFO  (qtp1341691261-9904) [    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> 1706135 INFO  (qtp1341691261-9907) [    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> 1706135 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Total time taken for download 
(fullCopy=false,bytesDownloaded=1822) : 0 secs (null bytes/sec) to 
MockDirectoryWrapper(RAMDirectory@76603a0a 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5738bd56)
   [junit4]   2> 1706136 WARN  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.h.IndexFetcher Exception while updating statistics
   [junit4]   2> java.io.IOException: file "replication.properties" was already 
written to
   [junit4]   2>        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:697)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:509)
   [junit4]   2>        at 
org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
   [junit4]   2>        at 
org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 1706137 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=37, maxMergeAtOnceExplicit=44, maxMergedSegmentMB=24.115234375, 
floorSegmentMB=2.029296875, forceMergeDeletesPctAllowed=10.98553308996783, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4273648615227591
   [junit4]   2> 1706147 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@1717b0cc 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@463a6841),segFN=segments_4,generation=4}
   [junit4]   2> 1706148 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 4
   [junit4]   2> 1706149 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
   [junit4]   2> 1706150 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@472323cf[collection1] main]
   [junit4]   2> 1706151 INFO  
(searcherExecutor-4232-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@472323cf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C1)
 Uninverting(_1(7.0.0):C1) Uninverting(_2(7.0.0):C1)))}
   [junit4]   2> 1706151 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834254
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834254;done=true>>]
   [junit4]   2> 1706151 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=false
   [junit4]   2> 1706151 INFO  (explicit-fetchindex-cmd) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-002/./collection1/data/index.20160918053834254
   [junit4]   2> 1706151 INFO  (qtp34205198-9920) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication 
params={wait=true&masterUrl=http://127.0.0.1:50490/solr/collection1/replication&command=fetchindex}
 status=0 QTime=105
   [junit4]   2> 1706152 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003/collection1
   [junit4]   2> 1706155 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 1706158 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5a04b99c{/solr,null,AVAILABLE}
   [junit4]   2> 1706158 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@3ba546b9{HTTP/1.1,[http/1.1]}{127.0.0.1:50355}
   [junit4]   2> 1706158 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.e.j.s.Server Started @1713110ms
   [junit4]   2> 1706158 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003/collection1/data,
 hostContext=/solr, hostPort=50355}
   [junit4]   2> 1706159 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@73d16e93
   [junit4]   2> 1706159 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003'
   [junit4]   2> 1706159 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1706159 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 1706159 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003/solr.xml
   [junit4]   2> 1706181 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003/.
   [junit4]   2> 1706181 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1384874116
   [junit4]   2> 1706181 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003]
   [junit4]   2> 1706181 WARN  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-003/lib
   [junit4]   2> 1706181 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,
   [junit4]   2> 1706187 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.u.UpdateShardHandler Created UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&maxConnections=100000&maxConnectionsPerHost=100000
   [junit4]   2> 1706187 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1706187 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1706187 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 1706187 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1706188 INFO  
(TEST-TestReplicationHandler.doTestDetails-seed#[2562BB911BE0EA96]) [    ] 
o.a.s.c.CorePropert

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

gDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-029/collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-029/collection1/data;done=false>>]
   [junit4]   2> 1822358 INFO  (coreCloseExecutor-4867-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 1822359 INFO  (coreCloseExecutor-4867-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-029/collection1/data
   [junit4]   2> 1822361 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[2562BB911BE0EA96])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@514328b7{/solr,null,UNAVAILABLE}
   [junit4]   2> 1822364 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[2562BB911BE0EA96])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@1b1215a8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1822364 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[2562BB911BE0EA96])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1147090914
   [junit4]   2> 1822372 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@5bfe9f36
   [junit4]   2> 1822373 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 closing 
DirectUpdateHandler2{commits=0,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1822373 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 1822373 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 
0 - closing IndexWriter
   [junit4]   2> 1822373 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 1822374 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
NRTCachingDirectoryFactory - 3 directories currently being tracked
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data;done=false>>]
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data/index
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data/index;done=false>>]
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data/index
   [junit4]   2> 1822386 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data/snapshot_metadata
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data/snapshot_metadata;done=false>>]
   [junit4]   2> 1822387 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, 
CoreContainer#isShutdown=true
   [junit4]   2> 1822387 INFO  (coreCloseExecutor-4869-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001/solr-instance-030/collection1/data/snapshot_metadata
   [junit4]   2> 1822389 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[2562BB911BE0EA96])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@66c17e1c{/solr,null,UNAVAILABLE}
   [junit4]   2> 1822396 INFO  
(SUITE-TestReplicationHandler-seed#[2562BB911BE0EA96]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler_2562BB911BE0EA96-001
   [junit4]   2> Sep 18, 2016 9:40:30 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{name=BlockTreeOrds(blocksize=128), id=BlockTreeOrds(blocksize=128), 
newname=BlockTreeOrds(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1344, 
maxMBSortInHeap=5.769562334786114, sim=ClassicSimilarity, locale=en-SG, 
timezone=America/Toronto
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 
1.8.0_102 (64-bit)/cpus=4,threads=1,free=293408064,total=477626368
   [junit4]   2> NOTE: All tests run in this JVM: [TestReload, 
CdcrUpdateLogTest, RecoveryZkTest, TestSizeLimitedDistributedMap, 
SpatialHeatmapFacetsTest, PluginInfoTest, TestFieldCacheSortRandom, 
InfoHandlerTest, CollectionsAPIAsyncDistributedZkTest, TestConfigSetsAPI, 
SuggesterTSTTest, TestTolerantSearch, DocValuesMultiTest, TestJettySolrRunner, 
CoreAdminHandlerTest, TestReplicationHandlerBackup, TestJsonRequest, 
ZkStateReaderTest, TestFreeTextSuggestions, TestRecovery, 
CurrencyFieldXmlFileTest, TestAnalyzeInfixSuggestions, TestShardHandlerFactory, 
TestDistributedStatsComponentCardinality, BadComponentTest, 
CoreAdminCreateDiscoverTest, BlockJoinFacetDistribTest, 
DistributedFacetPivotSmallTest, HdfsCollectionsAPIDistributedZkTest, 
DistribJoinFromCollectionTest, ShowFileRequestHandlerTest, 
DistributedDebugComponentTest, PreAnalyzedFieldTest, 
TestRequestStatusCollectionAPI, BinaryUpdateRequestHandlerTest, 
TestPostingsSolrHighlighter, HdfsThreadLeakTest, TestDFRSimilarityFactory, 
NoCacheHeaderTest, LukeRequestHandlerTest, OpenExchangeRatesOrgProviderTest, 
XmlUpdateRequestHandlerTest, CustomCollectionTest, ReplicationFactorTest, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrXml, EnumFieldTest, 
ForceLeaderTest, TestCollationFieldDocValues, TestZkChroot, 
TestSolrCloudWithSecureImpersonation, MergeStrategyTest, GraphQueryTest, 
TestManagedSynonymFilterFactory, TestJmxMonitoredMap, HLLSerializationTest, 
TestCryptoKeys, TestExtendedDismaxParser, ConfigSetsAPITest, 
SolrCLIZkUtilsTest, TestFilteredDocIdSet, BlockDirectoryTest, 
TestAuthenticationFramework, TestSchemaNameResource, RequestHandlersTest, 
TestTrackingShardHandlerFactory, ChangedSchemaMergeTest, 
SharedFSAutoReplicaFailoverTest, StatsComponentTest, TestSolrJ, 
TestInitQParser, HdfsBasicDistributedZkTest, HdfsRecoveryZkTest, 
TestStressReorder, TestExclusionRuleCollectionAccess, TestMiniSolrCloudCluster, 
TestDynamicFieldCollectionResource, AnalyticsMergeStrategyTest, TestLFUCache, 
DebugComponentTest, TestCoreDiscovery, TestSubQueryTransformerCrossCore, 
TestManagedResourceStorage, TestRebalanceLeaders, TestSearcherReuse, 
TestWriterPerf, SOLR749Test, TestSolrQueryParserResource, TestLRUStatsCache, 
HardAutoCommitTest, TestAuthorizationFramework, HdfsLockFactoryTest, 
TestExceedMaxTermLength, RecoveryAfterSoftCommitTest, SolrPluginUtilsTest, 
TestComplexPhraseQParserPlugin, TestLMJelinekMercerSimilarityFactory, 
TestObjectReleaseTracker, CircularListTest, OpenCloseCoreStressTest, 
OverseerTest, TestReplicationHandler]
   [junit4] Completed [318/631 (1!)] on J2 in 118.74s, 15 tests, 1 failure, 1 
skipped <<< FAILURES!

[...truncated 61209 lines...]


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

Reply via email to