lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 911 - Still Failing
Date Thu, 14 Jan 2016 08:00:01 GMT
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



Mime
View raw message