[JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 911 - Still Failing

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/911/

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

Error Message:
timed out waiting for collection1 startAt time to exceed: Thu Jan 14 13:22:11 
CCT 2016

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to 
exceed: Thu Jan 14 13:22:11 CCT 2016
        at 
__randomizedtesting.SeedInfo.seed([8C376E468C20FC16:579C6E80890895A5]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1419)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:771)
        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:497)
        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:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        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:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        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 10180 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/init-core-data-001
   [junit4]   2> 289484 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting testRateLimitedReplication
   [junit4]   2> 289485 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1
   [junit4]   2> 289490 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 289492 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6ee38521{/solr,null,AVAILABLE}
   [junit4]   2> 289501 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@330c98a9{HTTP/1.1}{127.0.0.1:54328}
   [junit4]   2> 289501 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.Server Started @292467ms
   [junit4]   2> 289502 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=54328}
   [junit4]   2> 289502 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 289502 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001'
   [junit4]   2> 289502 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr 
(NoInitialContextEx)
   [junit4]   2> 289502 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not 
find system property or JNDI)
   [junit4]   2> 289503 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/solr.xml
   [junit4]   2> 289508 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/.
   [junit4]   2> 289508 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer New CoreContainer 316561119
   [junit4]   2> 289508 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001]
   [junit4]   2> 289508 WARN  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/lib
   [junit4]   2> 289508 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] 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,useRetries : false,
   [junit4]   2> 289510 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 289510 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 289510 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 289510 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 289510 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 289511 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/.
   [junit4]   2> 289512 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] 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> 289512 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1
   [junit4]   2> 289512 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 289514 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1'
   [junit4]   2> 289514 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 289515 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 289515 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 289515 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 289522 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 289525 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 289532 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 289535 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 289537 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 289539 INFO  (coreLoadExecutor-514-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-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1'
   [junit4]   2> 289539 INFO  (coreLoadExecutor-514-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1
   [junit4]   2> 289540 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 289540 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 289540 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 289540 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data
   [junit4]   2> 289540 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index/
   [junit4]   2> 289540 WARN  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 289541 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index
   [junit4]   2> 289541 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.734375, noCFSRatio=0.0]
   [junit4]   2> 289542 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@67998fba 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f4f9bbc),segFN=segments_1,generation=1}
   [junit4]   2> 289542 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 289543 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-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/
   [junit4]   2> 289545 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 289548 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 289549 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 289550 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 289551 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 289558 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 289558 INFO  (coreLoadExecutor-514-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,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 289559 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 289559 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 289559 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 289560 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@69464d24
   [junit4]   2> 289560 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@67998fba 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f4f9bbc),segFN=segments_1,generation=1}
   [junit4]   2> 289560 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 289562 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@77ceec22[collection1] main]
   [junit4]   2> 289563 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/conf
   [junit4]   2> 289563 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/conf}
   [junit4]   2> 289563 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/conf
   [junit4]   2> 289563 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 289564 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 289564 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 289564 INFO  
(searcherExecutor-515-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@77ceec22[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 289564 INFO  (coreLoadExecutor-514-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 290017 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1
   [junit4]   2> 290027 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 290035 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3dc4434b{/solr,null,AVAILABLE}
   [junit4]   2> 290035 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@2de7eef7{HTTP/1.1}{127.0.0.1:60724}
   [junit4]   2> 290035 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.Server Started @293001ms
   [junit4]   2> 290036 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1/data,
 hostContext=/solr, hostPort=60724}
   [junit4]   2> 290036 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 290036 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002'
   [junit4]   2> 290036 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr 
(NoInitialContextEx)
   [junit4]   2> 290036 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not 
find system property or JNDI)
   [junit4]   2> 290036 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/solr.xml
   [junit4]   2> 290041 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/.
   [junit4]   2> 290041 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer New CoreContainer 1986373864
   [junit4]   2> 290041 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002]
   [junit4]   2> 290041 WARN  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/lib
   [junit4]   2> 290042 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] 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,useRetries : false,
   [junit4]   2> 290043 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 290044 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 290044 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 290044 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 290044 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 290045 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/.
   [junit4]   2> 290046 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] 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> 290046 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1
   [junit4]   2> 290046 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 290047 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1'
   [junit4]   2> 290066 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 290066 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 290067 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 290067 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 290096 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 290106 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 290111 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 290114 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 290116 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 290118 INFO  (coreLoadExecutor-524-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-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1'
   [junit4]   2> 290118 INFO  (coreLoadExecutor-524-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1
   [junit4]   2> 290118 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 290119 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/]
   [junit4]   2> 290119 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 290119 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data
   [junit4]   2> 290119 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/index/
   [junit4]   2> 290119 WARN  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 290120 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/index
   [junit4]   2> 290120 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=48, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.734375, noCFSRatio=0.0]
   [junit4]   2> 290120 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@dd3d260 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a9805e8),segFN=segments_1,generation=1}
   [junit4]   2> 290120 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 290126 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-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/
   [junit4]   2> 290128 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 290129 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290130 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290131 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290133 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290134 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 290135 INFO  (coreLoadExecutor-524-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,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 290135 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 290135 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 290135 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.MockRandomMergePolicy: 
org.apache.lucene.index.MockRandomMergePolicy@b64820f
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@dd3d260 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a9805e8),segFN=segments_1,generation=1}
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3c7216ca[collection1] main]
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1/conf
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1/conf}
   [junit4]   2> 290136 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/collection1/conf
   [junit4]   2> 290137 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 290140 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 290140 INFO  (coreLoadExecutor-524-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 290141 INFO  
(searcherExecutor-525-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3c7216ca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 290145 INFO  (qtp1327549838-1480) [    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> 290572 INFO  (qtp1327549838-1478) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 290572 INFO  (qtp1327549838-1478) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:*} 0 2
   [junit4]   2> 290576 INFO  (qtp695761174-1497) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 290577 INFO  (qtp695761174-1497) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1
   [junit4]   2> 290579 INFO  (qtp1327549838-1482) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 290580 INFO  (qtp1327549838-1482) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@67998fba 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f4f9bbc),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@67998fba 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f4f9bbc),segFN=segments_2,generation=2}
   [junit4]   2> 290581 INFO  (qtp1327549838-1482) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 290582 INFO  (qtp1327549838-1482) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14d9a96e[collection1] main]
   [junit4]   2> 290582 INFO  (qtp1327549838-1482) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 290582 INFO  
(searcherExecutor-515-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14d9a96e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 290582 INFO  (qtp1327549838-1482) [    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> 290585 INFO  (qtp695761174-1498) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 290586 INFO  (qtp695761174-1498) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@dd3d260 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a9805e8),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@dd3d260 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a9805e8),segFN=segments_2,generation=2}
   [junit4]   2> 290586 INFO  (qtp695761174-1498) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 290587 INFO  (qtp695761174-1498) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@9aa84bf[collection1] main]
   [junit4]   2> 290587 INFO  (qtp695761174-1498) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 290587 INFO  
(searcherExecutor-525-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@9aa84bf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 290587 INFO  (qtp695761174-1498) [    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> 290589 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@330c98a9{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 290589 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=316561119
   [junit4]   2> 290590 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@785e46a3
   [junit4]   2> 290590 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing 
DirectUpdateHandler2{commits=1,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=1,cumulative_errors=0}
   [junit4]   2> 290590 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 290590 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has 
reached 0 - closing IndexWriter
   [junit4]   2> 290590 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 290595 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 290595 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 290595 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index;done=false>>]
   [junit4]   2> 290595 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index
   [junit4]   2> 290595 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data;done=false>>]
   [junit4]   2> 290595 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data
   [junit4]   2> 290596 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6ee38521{/solr,null,UNAVAILABLE}
   [junit4]   2> 290622 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@2de7eef7{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 290622 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1986373864
   [junit4]   2> 290637 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6425d36a
   [junit4]   2> 290638 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing 
DirectUpdateHandler2{commits=1,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=1,cumulative_errors=0}
   [junit4]   2> 290638 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 290639 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has 
reached 0 - closing IndexWriter
   [junit4]   2> 290639 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 290639 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 290639 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 290639 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/index
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/index;done=false>>]
   [junit4]   2> 290639 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data/index
   [junit4]   2> 290640 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data;done=false>>]
   [junit4]   2> 290640 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-002/./collection1/data
   [junit4]   2> 290643 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3dc4434b{/solr,null,UNAVAILABLE}
   [junit4]   2> 290649 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 290663 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@430f4ec{/solr,null,AVAILABLE}
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@1025dc8a{HTTP/1.1}{127.0.0.1:53392}
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.Server Started @293630ms
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=53392}
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001'
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr 
(NoInitialContextEx)
   [junit4]   2> 290664 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not 
find system property or JNDI)
   [junit4]   2> 290665 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/solr.xml
   [junit4]   2> 290670 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/.
   [junit4]   2> 290670 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer New CoreContainer 1558398586
   [junit4]   2> 290670 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001]
   [junit4]   2> 290670 WARN  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Couldn't add files from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/lib
 to classpath: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/lib
   [junit4]   2> 290679 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] 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,useRetries : false,
   [junit4]   2> 290680 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 290681 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 290681 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 290681 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 290681 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 290682 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/.
   [junit4]   2> 290683 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] 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> 290683 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1
   [junit4]   2> 290683 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 290689 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1'
   [junit4]   2> 290689 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 290692 INFO  
(TEST-TestReplicationHandler.testRateLimitedReplication-seed#[8C376E468C20FC16])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 290692 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 290692 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 290701 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 290715 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 290737 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 290762 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 290765 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 290787 INFO  (coreLoadExecutor-535-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-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1'
   [junit4]   2> 290787 INFO  (coreLoadExecutor-535-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1
   [junit4]   2> 290787 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.NRTCachingDirectoryFactory
   [junit4]   2> 290797 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/]
   [junit4]   2> 290797 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 290798 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data
   [junit4]   2> 290798 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index/
   [junit4]   2> 290798 WARN  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 290801 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data/index
   [junit4]   2> 290801 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5222978728550988]
   [junit4]   2> 290842 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: 
num=1
   [junit4]   2>        
commit{dir=NRTCachingDirectory(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data/index
 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14ad87a9; 
maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2> 290842 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 290844 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 290846 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290847 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290848 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-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/./collection1/data/
   [junit4]   2> 290848 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290849 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 290852 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 290852 INFO  (coreLoadExecutor-535-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,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
   [junit4]   2> 290852 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 290853 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 290853 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 290853 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=48, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=49.21484375, 
floorSegmentMB=2.0732421875, forceMergeDeletesPctAllowed=20.922425965097506, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=1.4658203125, 
noCFSRatio=0.2269592434208459
   [junit4]   2> 290854 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: 
num=1
   [junit4]   2>        
commit{dir=NRTCachingDirectory(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data/index
 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14ad87a9; 
maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2> 290854 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 290854 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@57552503[collection1] main]
   [junit4]   2> 290854 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/conf
   [junit4]   2> 290854 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/conf}
   [junit4]   2> 290854 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/conf
   [junit4]   2> 290855 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 290855 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 290855 INFO  (coreLoadExecutor-535-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 290855 INFO  
(searcherExecutor-536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@57552503[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 291203 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[0]} 0 2
   [junit4]   2> 291206 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 291215 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[2]} 0 5
   [junit4]   2> 291218 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 291221 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 291223 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 291225 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[6]} 0 0
   [junit4]   2> 291229 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[7]} 0 1
   [junit4]   2> 291231 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[8]} 0 0
   [junit4]   2> 291234 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[9]} 0 0
   [junit4]   2> 291236 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[10]} 0 0
   [junit4]   2> 291239 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[11]} 0 0
   [junit4]   2> 291241 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[12]} 0 0
   [junit4]   2> 291245 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[13]} 0 1
   [junit4]   2> 291249 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[14]} 0 1
   [junit4]   2> 291251 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[15]} 0 0
   [junit4]   2> 291254 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[16]} 0 0
   [junit4]   2> 291256 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[17]} 0 0
   [junit4]   2> 291258 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[18]} 0 0
   [junit4]   2> 291262 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[19]} 0 1
   [junit4]   2> 291265 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[20]} 0 0
   [junit4]   2> 291267 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[21]} 0 0
   [junit4]   2> 291270 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[22]} 0 0
   [junit4]   2> 291272 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[23]} 0 0
   [junit4]   2> 291275 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[24]} 0 0
   [junit4]   2> 291278 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[25]} 0 1
   [junit4]   2> 291280 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[26]} 0 0
   [junit4]   2> 291283 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[27]} 0 0
   [junit4]   2> 291285 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[28]} 0 0
   [junit4]   2> 291288 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[29]} 0 0
   [junit4]   2> 291290 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[30]} 0 0
   [junit4]   2> 291293 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[31]} 0 1
   [junit4]   2> 291295 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[32]} 0 0
   [junit4]   2> 291298 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[33]} 0 0
   [junit4]   2> 291300 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[34]} 0 0
   [junit4]   2> 291302 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[35]} 0 0
   [junit4]   2> 291305 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[36]} 0 1
   [junit4]   2> 291307 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[37]} 0 0
   [junit4]   2> 291309 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[38]} 0 0
   [junit4]   2> 291312 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[39]} 0 0
   [junit4]   2> 291314 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[40]} 0 0
   [junit4]   2> 291316 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[41]} 0 0
   [junit4]   2> 291319 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[42]} 0 1
   [junit4]   2> 291322 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[43]} 0 0
   [junit4]   2> 291324 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[44]} 0 0
   [junit4]   2> 291327 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[45]} 0 0
   [junit4]   2> 291329 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[46]} 0 0
   [junit4]   2> 291332 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[47]} 0 1
   [junit4]   2> 291335 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[48]} 0 0
   [junit4]   2> 291339 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[49]} 0 2
   [junit4]   2> 291342 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[50]} 0 0
   [junit4]   2> 291344 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[51]} 0 0
   [junit4]   2> 291348 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[52]} 0 1
   [junit4]   2> 291350 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[53]} 0 0
   [junit4]   2> 291352 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[54]} 0 0
   [junit4]   2> 291354 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[55]} 0 0
   [junit4]   2> 291356 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[56]} 0 0
   [junit4]   2> 291358 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[57]} 0 0
   [junit4]   2> 291361 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[58]} 0 0
   [junit4]   2> 291364 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[59]} 0 1
   [junit4]   2> 291366 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[60]} 0 0
   [junit4]   2> 291368 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[61]} 0 0
   [junit4]   2> 291371 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[62]} 0 0
   [junit4]   2> 291373 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[63]} 0 0
   [junit4]   2> 291375 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[64]} 0 0
   [junit4]   2> 291378 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[65]} 0 1
   [junit4]   2> 291380 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[66]} 0 0
   [junit4]   2> 291383 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[67]} 0 0
   [junit4]   2> 291385 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[68]} 0 0
   [junit4]   2> 291387 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[69]} 0 0
   [junit4]   2> 291389 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[70]} 0 0
   [junit4]   2> 291391 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[71]} 0 0
   [junit4]   2> 291394 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[72]} 0 1
   [junit4]   2> 291397 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[73]} 0 0
   [junit4]   2> 291399 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[74]} 0 0
   [junit4]   2> 291401 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[75]} 0 0
   [junit4]   2> 291404 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[76]} 0 0
   [junit4]   2> 291407 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[77]} 0 1
   [junit4]   2> 291409 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[78]} 0 0
   [junit4]   2> 291412 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[79]} 0 0
   [junit4]   2> 291414 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[80]} 0 0
   [junit4]   2> 291416 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[81]} 0 0
   [junit4]   2> 291418 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[82]} 0 0
   [junit4]   2> 291422 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[83]} 0 1
   [junit4]   2> 291424 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[84]} 0 0
   [junit4]   2> 291426 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[85]} 0 0
   [junit4]   2> 291428 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[86]} 0 0
   [junit4]   2> 291431 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[87]} 0 0
   [junit4]   2> 291433 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[88]} 0 0
   [junit4]   2> 291435 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[89]} 0 0
   [junit4]   2> 291439 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[90]} 0 2
   [junit4]   2> 291451 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[91]} 0 3
   [junit4]   2> 291454 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[92]} 0 0
   [junit4]   2> 291456 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[93]} 0 0
   [junit4]   2> 291459 INFO  (qtp1086349459-1515) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[94]} 0 0
   [junit4]   2> 291461 INFO  (qtp1086349459-1519) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[95]} 0 0
   [junit4]   2> 291464 INFO  (qtp1086349459-1520) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[96]} 0 1
   [junit4]   2> 291467 INFO  (qtp1086349459-1521) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[97]} 0 0
   [junit4]   2> 291468 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 291520 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        
commit{dir=NRTCachingDirectory(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data/index
 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14ad87a9; 
maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2>        
commit{dir=NRTCachingDirectory(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-001/collection1/data/index
 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14ad87a9; 
maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
   [junit4]   2> 291521 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 291530 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1256666[collection1] main]
   [junit4]   2> 291531 INFO  
(searcherExecutor-536-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1256666[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C98)))}
   [junit4]   2> 291531 INFO  (qtp1086349459-1522) [    x:collection1] 
o.a.s.u.DirectUpdateHandl

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

.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing 
IndexWriter
   [junit4]   2> 797108 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 797110 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 797110 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 797110 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-027/./collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-027/./collection1/data;done=false>>]
   [junit4]   2> 797110 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-027/./collection1/data
   [junit4]   2> 797110 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-027/./collection1/data/index.20160114132918683
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-027/./collection1/data/index.20160114132918683;done=false>>]
   [junit4]   2> 797110 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-027/./collection1/data/index.20160114132918683
   [junit4]   2> 797111 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7d57c61a{/solr,null,UNAVAILABLE}
   [junit4]   2> 797112 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@27a06f3c{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 797112 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1545989326
   [junit4]   2> 797113 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1709385f
   [junit4]   2> 797113 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing 
DirectUpdateHandler2{commits=2,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
   [junit4]   2> 797113 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 797113 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has 
reached 0 - closing IndexWriter
   [junit4]   2> 797113 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 797114 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 797114 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 797114 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-028/./collection1/data
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-028/./collection1/data;done=false>>]
   [junit4]   2> 797114 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-028/./collection1/data
   [junit4]   2> 797114 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-028/./collection1/data/index.20160114132918347
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-028/./collection1/data/index.20160114132918347;done=false>>]
   [junit4]   2> 797114 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001/solr-instance-028/./collection1/data/index.20160114132918347
   [junit4]   2> 797115 INFO  
(TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[8C376E468C20FC16])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@347a22c3{/solr,null,UNAVAILABLE}
   [junit4]   2> 797116 INFO  
(SUITE-TestReplicationHandler-seed#[8C376E468C20FC16]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8C376E468C20FC16-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene60): 
{name=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting), 
newname=PostingsFormat(name=Asserting)}, docValues:{}, sim=ClassicSimilarity, 
locale=de_CH, timezone=Indian/Cocos
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_66 (64-bit)/cpus=4,threads=1,free=207999376,total=522190848
   [junit4]   2> NOTE: All tests run in this JVM: [RuleEngineTest, 
DateRangeFieldTest, TestDocSet, TestFreeTextSuggestions, TestFunctionQuery, 
URLClassifyProcessorTest, ConfigSetsAPITest, PrimUtilsTest, 
SharedFSAutoReplicaFailoverTest, DistributedFacetPivotLongTailTest, 
PeerSyncTest, SortSpecParsingTest, RAMDirectoryFactoryTest, DeleteShardTest, 
TestReplicationHandler]
   [junit4] Completed [80/576 (1!)] on J0 in 507.65s, 13 tests, 1 failure <<< 
FAILURES!

[...truncated 1594 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1424:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981:
 There were test failures: 576 suites (8 ignored), 2306 tests, 1 failure, 33 
ignored (18 assumptions) [seed: 8C376E468C20FC16]

Total time: 208 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



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