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

Apache Jenkins Server Wed, 19 Aug 2015 08:51:07 -0700

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


1 tests failed.
REGRESSION:  
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload

Error Message:
expected:<[{indexVersion=1439994299729,generation=2,filelist=[_0.fdt, _0.fdx, 
_0.fnm, _0.nvd, _0.nvm, _0.si, _0_LuceneVarGapFixedInterval_0.doc, 
_0_LuceneVarGapFixedInterval_0.tib, _0_LuceneVarGapFixedInterval_0.tiv, _1.fdt, 
_1.fdx, _1.fnm, _1.nvd, _1.nvm, _1.si, _1_LuceneVarGapFixedInterval_0.doc, 
_1_LuceneVarGapFixedInterval_0.tib, _1_LuceneVarGapFixedInterval_0.tiv, _2.fdt, 
_2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_LuceneVarGapFixedInterval_0.doc, 
_2_LuceneVarGapFixedInterval_0.tib, _2_LuceneVarGapFixedInterval_0.tiv, _3.fdt, 
_3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_LuceneVarGapFixedInterval_0.doc, 
_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdt, 
_4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_LuceneVarGapFixedInterval_0.doc, 
_4_LuceneVarGapFixedInterval_0.tib, _4_LuceneVarGapFixedInterval_0.tiv, _5.fdt, 
_5.fdx, _5.fnm, _5.nvd, _5.nvm, _5.si, _5_LuceneVarGapFixedInterval_0.doc, 
_5_LuceneVarGapFixedInterval_0.tib, _5_LuceneVarGapFixedInterval_0.tiv, 
segments_2]}]> but 
was:<[{indexVersion=1439994299729,generation=2,filelist=[_0.fdt, _0.fdx, 
_0.fnm, _0.nvd, _0.nvm, _0.si, _0_LuceneVarGapFixedInterval_0.doc, 
_0_LuceneVarGapFixedInterval_0.tib, _0_LuceneVarGapFixedInterval_0.tiv, _1.fdt, 
_1.fdx, _1.fnm, _1.nvd, _1.nvm, _1.si, _1_LuceneVarGapFixedInterval_0.doc, 
_1_LuceneVarGapFixedInterval_0.tib, _1_LuceneVarGapFixedInterval_0.tiv, _2.fdt, 
_2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_LuceneVarGapFixedInterval_0.doc, 
_2_LuceneVarGapFixedInterval_0.tib, _2_LuceneVarGapFixedInterval_0.tiv, _3.fdt, 
_3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_LuceneVarGapFixedInterval_0.doc, 
_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdt, 
_4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_LuceneVarGapFixedInterval_0.doc, 
_4_LuceneVarGapFixedInterval_0.tib, _4_LuceneVarGapFixedInterval_0.tiv, _5.fdt, 
_5.fdx, _5.fnm, _5.nvd, _5.nvm, _5.si, _5_LuceneVarGapFixedInterval_0.doc, 
_5_LuceneVarGapFixedInterval_0.tib, _5_LuceneVarGapFixedInterval_0.tiv, 
segments_2]}, {indexVersion=1439994299729,generation=3,filelist=[_6.fdt, 
_6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_LuceneVarGapFixedInterval_0.doc, 
_6_LuceneVarGapFixedInterval_0.tib, _6_LuceneVarGapFixedInterval_0.tiv, 
segments_3]}]>

Stack Trace:
java.lang.AssertionError: 
expected:<[{indexVersion=1439994299729,generation=2,filelist=[_0.fdt, _0.fdx, 
_0.fnm, _0.nvd, _0.nvm, _0.si, _0_LuceneVarGapFixedInterval_0.doc, 
_0_LuceneVarGapFixedInterval_0.tib, _0_LuceneVarGapFixedInterval_0.tiv, _1.fdt, 
_1.fdx, _1.fnm, _1.nvd, _1.nvm, _1.si, _1_LuceneVarGapFixedInterval_0.doc, 
_1_LuceneVarGapFixedInterval_0.tib, _1_LuceneVarGapFixedInterval_0.tiv, _2.fdt, 
_2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_LuceneVarGapFixedInterval_0.doc, 
_2_LuceneVarGapFixedInterval_0.tib, _2_LuceneVarGapFixedInterval_0.tiv, _3.fdt, 
_3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_LuceneVarGapFixedInterval_0.doc, 
_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdt, 
_4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_LuceneVarGapFixedInterval_0.doc, 
_4_LuceneVarGapFixedInterval_0.tib, _4_LuceneVarGapFixedInterval_0.tiv, _5.fdt, 
_5.fdx, _5.fnm, _5.nvd, _5.nvm, _5.si, _5_LuceneVarGapFixedInterval_0.doc, 
_5_LuceneVarGapFixedInterval_0.tib, _5_LuceneVarGapFixedInterval_0.tiv, 
segments_2]}]> but 
was:<[{indexVersion=1439994299729,generation=2,filelist=[_0.fdt, _0.fdx, 
_0.fnm, _0.nvd, _0.nvm, _0.si, _0_LuceneVarGapFixedInterval_0.doc, 
_0_LuceneVarGapFixedInterval_0.tib, _0_LuceneVarGapFixedInterval_0.tiv, _1.fdt, 
_1.fdx, _1.fnm, _1.nvd, _1.nvm, _1.si, _1_LuceneVarGapFixedInterval_0.doc, 
_1_LuceneVarGapFixedInterval_0.tib, _1_LuceneVarGapFixedInterval_0.tiv, _2.fdt, 
_2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_LuceneVarGapFixedInterval_0.doc, 
_2_LuceneVarGapFixedInterval_0.tib, _2_LuceneVarGapFixedInterval_0.tiv, _3.fdt, 
_3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_LuceneVarGapFixedInterval_0.doc, 
_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdt, 
_4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_LuceneVarGapFixedInterval_0.doc, 
_4_LuceneVarGapFixedInterval_0.tib, _4_LuceneVarGapFixedInterval_0.tiv, _5.fdt, 
_5.fdx, _5.fnm, _5.nvd, _5.nvm, _5.si, _5_LuceneVarGapFixedInterval_0.doc, 
_5_LuceneVarGapFixedInterval_0.tib, _5_LuceneVarGapFixedInterval_0.tiv, 
segments_2]}, {indexVersion=1439994299729,generation=3,filelist=[_6.fdt, 
_6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_LuceneVarGapFixedInterval_0.doc, 
_6_LuceneVarGapFixedInterval_0.tib, _6_LuceneVarGapFixedInterval_0.tiv, 
segments_3]}]>
        at 
__randomizedtesting.SeedInfo.seed([8946ACC34EE1012F:AC91B7F33EA90F2C]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:147)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1138)
        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:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        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:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        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:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        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:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 10952 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_8946ACC34EE1012F-001/init-core-data-001
   [junit4]   2> 2769399 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartup
   [junit4]   2> 2769400 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/collection1
   [junit4]   2> 2769427 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2769494 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@779c5d3{/solr,null,AVAILABLE}
   [junit4]   2> 2769496 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@4efacfea{HTTP/1.1}{127.0.0.1:40445}
   [junit4]   2> 2769496 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.Server Started @2772748ms
   [junit4]   2> 2769496 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=40445}
   [junit4]   2> 2769497 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 2769497 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/'
   [junit4]   2> 2769540 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/solr.xml
   [junit4]   2> 2769657 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/.
   [junit4]   2> 2769657 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer New CoreContainer 263782127
   [junit4]   2> 2769657 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/]
   [junit4]   2> 2769657 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/lib
   [junit4]   2> 2769658 WARN  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/lib).
   [junit4]   2> 2769673 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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> 2769674 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2769675 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2769675 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2769675 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2769675 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2769690 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/.
   [junit4]   2> 2769691 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1,
 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1/,
 dataDir=data/}
   [junit4]   2> 2769694 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1/
   [junit4]   2> 2769694 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2769697 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/'
   [junit4]   2> 2769706 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 2769706 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2769738 INFO  (coreLoadExecutor-4747-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2769780 INFO  (coreLoadExecutor-4747-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2769786 INFO  (coreLoadExecutor-4747-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2769787 INFO  (coreLoadExecutor-4747-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf/schema.xml
   [junit4]   2> 2769789 INFO  (coreLoadExecutor-4747-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2769795 INFO  (coreLoadExecutor-4747-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2769797 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf'
   [junit4]   2> 2769797 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/
   [junit4]   2> 2769797 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2769797 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/],
 dataDir=[null]
   [junit4]   2> 2769797 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 2769802 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data
   [junit4]   2> 2769803 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index/
   [junit4]   2> 2769803 WARN  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2769803 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index
   [junit4]   2> 2769803 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=45, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=0.662109375, 
floorSegmentMB=2.021484375, forceMergeDeletesPctAllowed=3.538941415176601, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1876443897656941
   [junit4]   2> 2769814 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@28699121 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e65a500),segFN=segments_1,generation=1}
   [junit4]   2> 2769814 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2769822 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 2769824 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2769824 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2769825 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2769826 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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/
   [junit4]   2> 2769830 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2769846 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 2769846 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 2769847 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2769851 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2769852 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2769858 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=6, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4225107653811767]
   [junit4]   2> 2769860 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@28699121 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e65a500),segFN=segments_1,generation=1}
   [junit4]   2> 2769860 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2769860 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7d349981[collection1] 
main
   [junit4]   2> 2769861 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf
   [junit4]   2> 2769861 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf}
   [junit4]   2> 2769861 INFO  (coreLoadExecutor-4747-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf
   [junit4]   2> 2769861 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2769862 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 2769862 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2769862 INFO  (coreLoadExecutor-4747-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2769862 INFO  
(searcherExecutor-4748-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7d349981[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2770208 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-002/collection1
   [junit4]   2> 2770216 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2770306 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2cb0816a{/solr,null,AVAILABLE}
   [junit4]   2> 2770307 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@4facd91b{HTTP/1.1}{127.0.0.1:60377}
   [junit4]   2> 2770307 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.Server Started @2773559ms
   [junit4]   2> 2770307 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-002/collection1/data,
 hostContext=/solr, hostPort=60377}
   [junit4]   2> 2770307 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 2770307 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-002/'
   [junit4]   2> 2770324 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-002/solr.xml
   [junit4]   2> 2770341 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/.
   [junit4]   2> 2770342 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer New CoreContainer 185836208
   [junit4]   2> 2770342 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-002/]
   [junit4]   2> 2770342 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/lib
   [junit4]   2> 2770342 WARN  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/lib).
   [junit4]   2> 2770381 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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> 2770384 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2770384 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2770385 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2770385 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2770385 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2770386 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/.
   [junit4]   2> 2770387 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/./collection1,
 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/./collection1/,
 dataDir=data/}
   [junit4]   2> 2770387 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/./collection1/
   [junit4]   2> 2770387 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2770398 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/'
   [junit4]   2> 2770418 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 2770418 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2770451 INFO  (coreLoadExecutor-4757-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2770464 INFO  (coreLoadExecutor-4757-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2770471 INFO  (coreLoadExecutor-4757-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2770475 INFO  (coreLoadExecutor-4757-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-002/./collection1/conf/schema.xml
   [junit4]   2> 2770485 INFO  (coreLoadExecutor-4757-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2770487 INFO  (coreLoadExecutor-4757-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2770489 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/conf'
   [junit4]   2> 2770489 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/
   [junit4]   2> 2770489 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2770489 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/],
 dataDir=[null]
   [junit4]   2> 2770489 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 2770490 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data
   [junit4]   2> 2770490 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data/index/
   [junit4]   2> 2770490 WARN  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2770503 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data/index
   [junit4]   2> 2770503 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=45, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=0.662109375, 
floorSegmentMB=2.021484375, forceMergeDeletesPctAllowed=3.538941415176601, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1876443897656941
   [junit4]   2> 2770507 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3fb54a69 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46aaae6a),segFN=segments_1,generation=1}
   [junit4]   2> 2770507 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2770517 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 2770520 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2770521 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2770522 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2770523 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2770524 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 2770524 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 2770525 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2770525 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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data/
   [junit4]   2> 2770534 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2770534 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2770534 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=6, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4225107653811767]
   [junit4]   2> 2770535 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@3fb54a69 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@46aaae6a),segFN=segments_1,generation=1}
   [junit4]   2> 2770535 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2770535 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@31eb2ddb[collection1] 
main
   [junit4]   2> 2770536 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/conf
   [junit4]   2> 2770536 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/conf}
   [junit4]   2> 2770536 INFO  (coreLoadExecutor-4757-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/conf
   [junit4]   2> 2770536 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2770543 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 
1000ms
   [junit4]   2> 2770544 INFO  (coreLoadExecutor-4757-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2770544 INFO  
(searcherExecutor-4758-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@31eb2ddb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2770926 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@4facd91b{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 2770927 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=185836208
   [junit4]   2> 2770935 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7dfc47c5
   [junit4]   2> 2770936 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=0,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 2770937 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 2770937 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has 
reached 0 - closing IndexWriter
   [junit4]   2> 2770937 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 2770938 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 2770938 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2770938 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data;done=false>>]
   [junit4]   2> 2770939 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data
   [junit4]   2> 2770939 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data/index;done=false>>]
   [junit4]   2> 2770939 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-002/./collection1/data/index
   [junit4]   2> 2770940 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2cb0816a{/solr,null,UNAVAILABLE}
   [junit4]   2> 2770949 INFO  (qtp1823502744-105078) [    x:collection1] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 2770951 INFO  (qtp1823502744-105078) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
   [junit4]   2> 2770954 INFO  (qtp1823502744-105079) [    x:collection1] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2770957 INFO  (qtp1823502744-105079) [    x:collection1] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@28699121 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e65a500),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@28699121 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e65a500),segFN=segments_2,generation=2}
   [junit4]   2> 2770958 INFO  (qtp1823502744-105079) [    x:collection1] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 2770960 INFO  (qtp1823502744-105079) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@31a6fe72[collection1] main
   [junit4]   2> 2770960 INFO  (qtp1823502744-105079) [    x:collection1] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2770960 INFO  
(searcherExecutor-4748-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@31a6fe72[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2770962 INFO  (qtp1823502744-105079) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} 
{commit=} 0 8
   [junit4]   2> 2770975 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.ServerConnector Stopped 
ServerConnector@4efacfea{HTTP/1.1}{127.0.0.1:0}
   [junit4]   2> 2770975 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=263782127
   [junit4]   2> 2770976 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3c9bb630
   [junit4]   2> 2770976 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.UpdateHandler 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> 2770980 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 2770980 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has 
reached 0 - closing IndexWriter
   [junit4]   2> 2770980 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 2770981 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 2770981 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2770981 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index;done=false>>]
   [junit4]   2> 2770981 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index
   [junit4]   2> 2770981 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data;done=false>>]
   [junit4]   2> 2770982 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data
   [junit4]   2> 2770982 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@779c5d3{/solr,null,UNAVAILABLE}
   [junit4]   2> 2770994 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 2771070 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@ff8455c{/solr,null,AVAILABLE}
   [junit4]   2> 2771071 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.ServerConnector Started 
ServerConnector@26aa62bd{HTTP/1.1}{127.0.0.1:50652}
   [junit4]   2> 2771071 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.e.j.s.Server Started @2774323ms
   [junit4]   2> 2771071 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/collection1/data,
 hostContext=/solr, hostPort=50652}
   [junit4]   2> 2771071 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@4e0e2f2a
   [junit4]   2> 2771071 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/'
   [junit4]   2> 2771097 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/solr.xml
   [junit4]   2> 2771110 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/.
   [junit4]   2> 2771110 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer New CoreContainer 1255029828
   [junit4]   2> 2771110 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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_8946ACC34EE1012F-001/solr-instance-001/]
   [junit4]   2> 2771110 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/lib
   [junit4]   2> 2771111 WARN  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/lib).
   [junit4]   2> 2771129 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] 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> 2771133 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with 
params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2771133 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2771134 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2771141 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 2771141 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2771144 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Looking for core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/.
   [junit4]   2> 2771145 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
configSetProperties=configsetprops.json, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1,
 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1/,
 dataDir=data/}
   [junit4]   2> 2771145 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1/
   [junit4]   2> 2771146 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 2771158 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/'
   [junit4]   2> 2771169 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
   [junit4]   2> 2771169 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterStartup-seed#[8946ACC34EE1012F])
 [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 2771239 INFO  (coreLoadExecutor-4768-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2771258 INFO  (coreLoadExecutor-4768-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2771274 INFO  (coreLoadExecutor-4768-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2771285 INFO  (coreLoadExecutor-4768-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf/schema.xml
   [junit4]   2> 2771287 INFO  (coreLoadExecutor-4768-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2771289 INFO  (coreLoadExecutor-4768-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2771290 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf'
   [junit4]   2> 2771290 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/
   [junit4]   2> 2771290 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2771291 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/],
 dataDir=[null]
   [junit4]   2> 2771291 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 2771291 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data
   [junit4]   2> 2771291 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index/
   [junit4]   2> 2771292 WARN  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2771292 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/index
   [junit4]   2> 2771292 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=45, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=0.662109375, 
floorSegmentMB=2.021484375, forceMergeDeletesPctAllowed=3.538941415176601, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1876443897656941
   [junit4]   2> 2771330 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@c407843 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263da79b),segFN=segments_1,generation=1}
   [junit4]   2> 2771330 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2771332 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 2771334 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2771334 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2771335 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2771336 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2771338 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 2771338 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 2771338 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2771339 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_8946ACC34EE1012F-001/solr-instance-001/./collection1/data/
   [junit4]   2> 2771343 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2771343 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2771344 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=6, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4225107653811767]
   [junit4]   2> 2771347 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@c407843 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@263da79b),segFN=segments_1,generation=1}
   [junit4]   2> 2771347 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2771347 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@59e372f[collection1] 
main
   [junit4]   2> 2771348 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf
   [junit4]   2> 2771348 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf}
   [junit4]   2> 2771348 INFO  (coreLoadExecutor-4768-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_8946ACC34EE1012F-001/solr-instance-001/./collection1/conf
   [junit4]   2> 2771349 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2771349 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following 
config files: schema.xml
   [junit4]   2> 2771349 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2771350 INFO  (coreLoadExecutor-4768-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2771350 INFO  
(searcherExecutor-4769-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@59e372f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2771717 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[0]} 0 1
   [junit4]   2> 2771719 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[1]} 0 0
   [junit4]   2> 2771722 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[2]} 0 0
   [junit4]   2> 2771725 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[3]} 0 0
   [junit4]   2> 2771728 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[4]} 0 0
   [junit4]   2> 2771730 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[5]} 0 0
   [junit4]   2> 2771732 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[6]} 0 0
   [junit4]   2> 2771744 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[7]} 0 0
   [junit4]   2> 2771746 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[8]} 0 0
   [junit4]   2> 2771748 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[9]} 0 0
   [junit4]   2> 2771750 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[10]} 0 0
   [junit4]   2> 2771751 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[11]} 0 0
   [junit4]   2> 2771754 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[12]} 0 0
   [junit4]   2> 2771756 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[13]} 0 0
   [junit4]   2> 2771758 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[14]} 0 0
   [junit4]   2> 2771760 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[15]} 0 0
   [junit4]   2> 2771762 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[16]} 0 0
   [junit4]   2> 2771764 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[17]} 0 0
   [junit4]   2> 2771766 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[18]} 0 0
   [junit4]   2> 2771768 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[19]} 0 0
   [junit4]   2> 2771770 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[20]} 0 0
   [junit4]   2> 2771774 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[21]} 0 0
   [junit4]   2> 2771776 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[22]} 0 0
   [junit4]   2> 2771778 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[23]} 0 0
   [junit4]   2> 2771780 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[24]} 0 0
   [junit4]   2> 2771781 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[25]} 0 0
   [junit4]   2> 2771783 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[26]} 0 0
   [junit4]   2> 2771785 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[27]} 0 0
   [junit4]   2> 2771790 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[28]} 0 0
   [junit4]   2> 2771792 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[29]} 0 0
   [junit4]   2> 2771795 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[30]} 0 0
   [junit4]   2> 2771797 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[31]} 0 0
   [junit4]   2> 2771799 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[32]} 0 0
   [junit4]   2> 2771800 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[33]} 0 0
   [junit4]   2> 2771802 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[34]} 0 0
   [junit4]   2> 2771804 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[35]} 0 0
   [junit4]   2> 2771806 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[36]} 0 0
   [junit4]   2> 2771808 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[37]} 0 0
   [junit4]   2> 2771809 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[38]} 0 0
   [junit4]   2> 2771811 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[39]} 0 0
   [junit4]   2> 2771813 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[40]} 0 0
   [junit4]   2> 2771818 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[41]} 0 0
   [junit4]   2> 2771820 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[42]} 0 0
   [junit4]   2> 2771822 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[43]} 0 0
   [junit4]   2> 2771824 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[44]} 0 0
   [junit4]   2> 2771825 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[45]} 0 0
   [junit4]   2> 2771827 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[46]} 0 0
   [junit4]   2> 2771829 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[47]} 0 0
   [junit4]   2> 2771831 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[48]} 0 0
   [junit4]   2> 2771833 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[49]} 0 0
   [junit4]   2> 2771834 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[50]} 0 0
   [junit4]   2> 2771836 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[51]} 0 0
   [junit4]   2> 2771838 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[52]} 0 0
   [junit4]   2> 2771840 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[53]} 0 0
   [junit4]   2> 2771841 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[54]} 0 0
   [junit4]   2> 2771843 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[55]} 0 0
   [junit4]   2> 2771850 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[56]} 0 0
   [junit4]   2> 2771852 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[57]} 0 0
   [junit4]   2> 2771854 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[58]} 0 0
   [junit4]   2> 2771856 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[59]} 0 0
   [junit4]   2> 2771858 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[60]} 0 0
   [junit4]   2> 2771860 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[61]} 0 0
   [junit4]   2> 2771861 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[62]} 0 0
   [junit4]   2> 2771864 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[63]} 0 0
   [junit4]   2> 2771865 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[64]} 0 0
   [junit4]   2> 2771867 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[65]} 0 0
   [junit4]   2> 2771869 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[66]} 0 0
   [junit4]   2> 2771871 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[67]} 0 0
   [junit4]   2> 2771873 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[68]} 0 0
   [junit4]   2> 2771874 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[69]} 0 0
   [junit4]   2> 2771876 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[70]} 0 0
   [junit4]   2> 2771878 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[71]} 0 0
   [junit4]   2> 2771880 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[72]} 0 0
   [junit4]   2> 2771882 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[73]} 0 0
   [junit4]   2> 2771884 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[74]} 0 0
   [junit4]   2> 2771889 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[75]} 0 0
   [junit4]   2> 2771891 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[76]} 0 0
   [junit4]   2> 2771893 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[77]} 0 0
   [junit4]   2> 2771895 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[78]} 0 0
   [junit4]   2> 2771897 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[79]} 0 0
   [junit4]   2> 2771900 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[80]} 0 0
   [junit4]   2> 2771902 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[81]} 0 0
   [junit4]   2> 2771903 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[82]} 0 0
   [junit4]   2> 2771905 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[83]} 0 0
   [junit4]   2> 2771907 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[84]} 0 0
   [junit4]   2> 2771909 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[85]} 0 0
   [junit4]   2> 2771910 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[86]} 0 0
   [junit4]   2> 2771912 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[87]} 0 0
   [junit4]   2> 2771914 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[88]} 0 0
   [junit4]   2> 2771916 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[89]} 0 0
   [junit4]   2> 2771919 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[90]} 0 0
   [junit4]   2> 2771921 INFO  (qtp1402488079-105119) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[91]} 0 0
   [junit4]   2> 2771923 INFO  (qtp1402488079-105120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[92]} 0 0
   [junit4]   2> 2771925 INFO  (qtp1402488079-105121) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[93]} 0 0
   [junit4]   2> 2771926 INFO  (qtp1402488079-105122) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2} {add=[94]} 0 0
   [junit4]   2> 2771929 INFO  (qtp1402488079-105118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update 
params={wt=javabin&version=2

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

 [junit4]   2> 3580304 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 3580305 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on 
request.
   [junit4]   2> 3580305 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 3580306 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-028/./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_8946ACC34EE1012F-001/solr-instance-028/./collection1/data/index;done=false>>]
   [junit4]   2> 3580306 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-028/./collection1/data/index
   [junit4]   2> 3580306 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-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_8946ACC34EE1012F-001/solr-instance-028/./collection1/data;done=false>>]
   [junit4]   2> 3580306 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    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_8946ACC34EE1012F-001/solr-instance-028/./collection1/data
   [junit4]   2> 3580307 INFO  
(TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[8946ACC34EE1012F])
 [    x:collection1] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2ffe6a06{/solr,null,UNAVAILABLE}
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestReplicationHandler -Dtests.method=doTestReplicateAfterCoreReload 
-Dtests.seed=8946ACC34EE1012F -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=es_NI -Dtests.timezone=Kwajalein -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE  460s J0 | 
TestReplicationHandler.doTestReplicateAfterCoreReload <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: 
expected:<[{indexVersion=1439994299729,generation=2,filelist=[_0.fdt, _0.fdx, 
_0.fnm, _0.nvd, _0.nvm, _0.si, _0_LuceneVarGapFixedInterval_0.doc, 
_0_LuceneVarGapFixedInterval_0.tib, _0_LuceneVarGapFixedInterval_0.tiv, _1.fdt, 
_1.fdx, _1.fnm, _1.nvd, _1.nvm, _1.si, _1_LuceneVarGapFixedInterval_0.doc, 
_1_LuceneVarGapFixedInterval_0.tib, _1_LuceneVarGapFixedInterval_0.tiv, _2.fdt, 
_2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_LuceneVarGapFixedInterval_0.doc, 
_2_LuceneVarGapFixedInterval_0.tib, _2_LuceneVarGapFixedInterval_0.tiv, _3.fdt, 
_3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_LuceneVarGapFixedInterval_0.doc, 
_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdt, 
_4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_LuceneVarGapFixedInterval_0.doc, 
_4_LuceneVarGapFixedInterval_0.tib, _4_LuceneVarGapFixedInterval_0.tiv, _5.fdt, 
_5.fdx, _5.fnm, _5.nvd, _5.nvm, _5.si, _5_LuceneVarGapFixedInterval_0.doc, 
_5_LuceneVarGapFixedInterval_0.tib, _5_LuceneVarGapFixedInterval_0.tiv, 
segments_2]}]> but 
was:<[{indexVersion=1439994299729,generation=2,filelist=[_0.fdt, _0.fdx, 
_0.fnm, _0.nvd, _0.nvm, _0.si, _0_LuceneVarGapFixedInterval_0.doc, 
_0_LuceneVarGapFixedInterval_0.tib, _0_LuceneVarGapFixedInterval_0.tiv, _1.fdt, 
_1.fdx, _1.fnm, _1.nvd, _1.nvm, _1.si, _1_LuceneVarGapFixedInterval_0.doc, 
_1_LuceneVarGapFixedInterval_0.tib, _1_LuceneVarGapFixedInterval_0.tiv, _2.fdt, 
_2.fdx, _2.fnm, _2.nvd, _2.nvm, _2.si, _2_LuceneVarGapFixedInterval_0.doc, 
_2_LuceneVarGapFixedInterval_0.tib, _2_LuceneVarGapFixedInterval_0.tiv, _3.fdt, 
_3.fdx, _3.fnm, _3.nvd, _3.nvm, _3.si, _3_LuceneVarGapFixedInterval_0.doc, 
_3_LuceneVarGapFixedInterval_0.tib, _3_LuceneVarGapFixedInterval_0.tiv, _4.fdt, 
_4.fdx, _4.fnm, _4.nvd, _4.nvm, _4.si, _4_LuceneVarGapFixedInterval_0.doc, 
_4_LuceneVarGapFixedInterval_0.tib, _4_LuceneVarGapFixedInterval_0.tiv, _5.fdt, 
_5.fdx, _5.fnm, _5.nvd, _5.nvm, _5.si, _5_LuceneVarGapFixedInterval_0.doc, 
_5_LuceneVarGapFixedInterval_0.tib, _5_LuceneVarGapFixedInterval_0.tiv, 
segments_2]}, {indexVersion=1439994299729,generation=3,filelist=[_6.fdt, 
_6.fdx, _6.fnm, _6.nvd, _6.nvm, _6.si, _6_LuceneVarGapFixedInterval_0.doc, 
_6_LuceneVarGapFixedInterval_0.tib, _6_LuceneVarGapFixedInterval_0.tiv, 
segments_3]}]>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([8946ACC34EE1012F:AC91B7F33EA90F2C]:0)
   [junit4]    >        at 
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1138)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 3580394 INFO  
(SUITE-TestReplicationHandler-seed#[8946ACC34EE1012F]-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_8946ACC34EE1012F-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): 
{name=PostingsFormat(name=LuceneVarGapFixedInterval), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), 
newname=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, 
sim=DefaultSimilarity, locale=es_NI, timezone=Kwajalein
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_45 (64-bit)/cpus=4,threads=1,free=160658984,total=516947968
   [junit4]   2> NOTE: All tests run in this JVM: [MultiTermTest, 
TestSha256AuthenticationProvider, SuggesterFSTTest, 
TestFieldTypeCollectionResource, StandardRequestHandlerTest, 
TestCloudManagedSchemaConcurrent, TestDFRSimilarityFactory, 
TestSolrConfigHandlerCloud, TestSmileRequest, TestClusterStateMutator, 
TestCloudPivotFacet, MinimalSchemaTest, TestUpdate, TestMergePolicyConfig, 
CursorMarkTest, DistributedFacetPivotLargeTest, PeerSyncTest, 
LeaderElectionIntegrationTest, AddBlockUpdateTest, HighlighterConfigTest, 
TestStressLucene, TestSchemaNameResource, HdfsBasicDistributedZkTest, 
TestJsonFacets, TestRangeQuery, HighlighterTest, 
DistributedSpellCheckComponentTest, CdcrReplicationDistributedZkTest, 
TestSweetSpotSimilarityFactory, TestPhraseSuggestions, 
TestAuthorizationFramework, TestShardHandlerFactory, 
TestDefaultSearchFieldResource, CloudExitableDirectoryReaderTest, 
TestRawResponseWriter, SimplePostToolTest, TestLRUStatsCache, 
TestFieldTypeResource, BigEndianAscendingWordSerializerTest, BlockCacheTest, 
BasicZkTest, HttpPartitionTest, CurrencyFieldXmlFileTest, TestBadConfig, 
CdcrUpdateLogTest, DistributedSuggestComponentTest, RequestHandlersTest, 
FastVectorHighlighterTest, TestDocumentBuilder, 
TestReversedWildcardFilterFactory, TestCryptoKeys, TestJoin, 
TestAuthenticationFramework, AnalyticsMergeStrategyTest, 
ChaosMonkeyNothingIsSafeTest, DeleteInactiveReplicaTest, 
LeaderFailoverAfterPartitionTest, DocValuesMissingTest, IndexSchemaTest, 
TestScoreJoinQPScore, BasicDistributedZkTest, UUIDFieldTest, 
DistributedFacetPivotLongTailTest, TestReplicaProperties, TestRealTimeGet, 
SignatureUpdateProcessorFactoryTest, LeaderInitiatedRecoveryOnCommitTest, 
TestReplicationHandler]
   [junit4] Completed [405/540] on J0 in 811.06s, 13 tests, 1 failure <<< 
FAILURES!

[...truncated 458 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719:
 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:514:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1429:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:984:
 There were test failures: 540 suites, 2131 tests, 1 failure, 26 ignored (14 
assumptions)

Total time: 222 minutes 17 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
Sending email for trigger: Failure

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

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

Reply via email to