lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0-ea-b99) - Build # 6932 - Failure!
Date Fri, 09 Aug 2013 00:40:39 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/6932/
Java: 32bit/jdk1.8.0-ea-b99 -server -XX:+UseSerialGC

2 tests failed.
REGRESSION:  org.apache.solr.handler.TestReplicationHandler.doTestStressReplication

Error Message:
timed out waiting for collection1 startAt time to exceed: Fri Aug 09 04:32:31 SAMT 2013

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Fri Aug 09 04:32:31 SAMT 2013
	at __randomizedtesting.SeedInfo.seed([8E71EF39F4D18009:55DAEFFFF1F9E9BA]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1507)
	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:811)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:491)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	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:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	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:358)
	at java.lang.Thread.run(Thread.java:724)


REGRESSION:  org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication

Error Message:
timed out waiting for collection1 startAt time to exceed: Fri Aug 09 04:33:15 SAMT 2013

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Fri Aug 09 04:33:15 SAMT 2013
	at __randomizedtesting.SeedInfo.seed([8E71EF39F4D18009:7902016132392FEF]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1507)
	at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1256)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:491)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	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:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	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:358)
	at java.lang.Thread.run(Thread.java:724)




Build Log:
[...truncated 9865 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> 679494 T2591 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterCoreReload
   [junit4]   2> 679498 T2591 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 679518 T2591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51645
   [junit4]   2> 679519 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 679519 T2591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 679519 T2591 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master
   [junit4]   2> 679520 T2591 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/'
   [junit4]   2> 679529 T2591 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/solr.xml
   [junit4]   2> 679530 T2591 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/solr.xml does not exist, using default configuration
   [junit4]   2> 679537 T2591 oasc.CoreContainer.<init> New CoreContainer 8188929
   [junit4]   2> 679537 T2591 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/]
   [junit4]   2> 679538 T2591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 679538 T2591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 679538 T2591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 679538 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 679539 T2591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 679539 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 679539 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 679539 T2591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 679540 T2591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 679540 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 679545 T2601 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1
   [junit4]   2> 679545 T2601 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/'
   [junit4]   2> 679558 T2601 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 679573 T2601 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 679573 T2601 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 679575 T2601 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 679578 T2601 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 679578 T2601 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 679578 T2601 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/
   [junit4]   2> 679579 T2601 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 679579 T2601 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data
   [junit4]   2> 679579 T2601 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index/
   [junit4]   2> 679579 T2601 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 679580 T2601 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index
   [junit4]   2> 679580 T2601 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b27c14 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@799972),segFN=segments_1,generation=1}
   [junit4]   2> 679581 T2601 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679581 T2601 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 679581 T2601 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 679582 T2601 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 679582 T2601 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 679582 T2601 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 679582 T2601 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 679584 T2601 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 679584 T2601 oass.SolrIndexSearcher.<init> Opening Searcher@1b58d0c main
   [junit4]   2> 679585 T2601 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 679585 T2601 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 679585 T2601 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 679585 T2601 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 679585 T2602 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b58d0c main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 679585 T2601 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 679586 T2591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 679586 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 679587 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 679591 T2591 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 679594 T2591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:56968
   [junit4]   2> 679594 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 679594 T2591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 679595 T2591 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave
   [junit4]   2> 679595 T2591 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/'
   [junit4]   2> 679602 T2591 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/solr.xml
   [junit4]   2> 679603 T2591 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/solr.xml does not exist, using default configuration
   [junit4]   2> 679608 T2591 oasc.CoreContainer.<init> New CoreContainer 27384367
   [junit4]   2> 679608 T2591 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/]
   [junit4]   2> 679609 T2591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 679609 T2591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 679609 T2591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 679610 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 679610 T2591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 679610 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 679610 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 679611 T2591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 679611 T2591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 679611 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 679615 T2613 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1
   [junit4]   2> 679615 T2613 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/'
   [junit4]   2> 679628 T2613 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 679634 T2613 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 679634 T2613 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 679636 T2613 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 679638 T2613 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 679639 T2613 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 679639 T2613 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/
   [junit4]   2> 679639 T2613 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 679639 T2613 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data
   [junit4]   2> 679640 T2613 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index/
   [junit4]   2> 679640 T2613 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 679640 T2613 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index
   [junit4]   2> 679641 T2613 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2a4166 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@139bd72),segFN=segments_1,generation=1}
   [junit4]   2> 679641 T2613 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679642 T2613 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 679642 T2613 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 679642 T2613 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 679642 T2613 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 679642 T2613 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 679642 T2613 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 679643 T2613 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 679644 T2613 oass.SolrIndexSearcher.<init> Opening Searcher@c0c4b main
   [junit4]   2> 679644 T2613 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 679645 T2613 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 679645 T2613 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 679645 T2614 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c0c4b main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 679645 T2613 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 679646 T2591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 679646 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 679647 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 679672 T2591 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27384367
   [junit4]   2> 679673 T2591 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@551b50
   [junit4]   2> 679673 T2591 oasu.DirectUpdateHandler2.close 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> 679674 T2591 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 679674 T2591 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 679674 T2591 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 679675 T2591 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 679676 T2591 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 679676 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index;done=false>>]
   [junit4]   2> 679676 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index
   [junit4]   2> 679677 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data;done=false>>]
   [junit4]   2> 679677 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data
   [junit4]   2> 679678 T2591 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 679751 T2591 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8188929
   [junit4]   2> 679751 T2591 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@bfe7bd
   [junit4]   2> 679752 T2591 oasu.DirectUpdateHandler2.close 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> 679752 T2591 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 679752 T2591 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 679752 T2591 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 679753 T2591 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 679753 T2591 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 679754 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data;done=false>>]
   [junit4]   2> 679754 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data
   [junit4]   2> 679754 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index;done=false>>]
   [junit4]   2> 679755 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index
   [junit4]   2> 679755 T2591 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 679807 T2591 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 679809 T2591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51957
   [junit4]   2> 679809 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 679809 T2591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 679810 T2591 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master
   [junit4]   2> 679810 T2591 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/'
   [junit4]   2> 679818 T2591 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/solr.xml
   [junit4]   2> 679819 T2591 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/solr.xml does not exist, using default configuration
   [junit4]   2> 679833 T2591 oasc.CoreContainer.<init> New CoreContainer 4244256
   [junit4]   2> 679833 T2591 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/]
   [junit4]   2> 679834 T2591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 679834 T2591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 679834 T2591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 679835 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 679835 T2591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 679835 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 679835 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 679836 T2591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 679836 T2591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 679836 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 679840 T2626 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1
   [junit4]   2> 679840 T2626 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/'
   [junit4]   2> 679853 T2626 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 679859 T2626 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 679860 T2626 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 679862 T2626 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 679864 T2626 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 679864 T2626 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 679864 T2626 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/
   [junit4]   2> 679864 T2626 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 679865 T2626 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data
   [junit4]   2> 679865 T2626 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index/
   [junit4]   2> 679865 T2626 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 679865 T2626 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index
   [junit4]   2> 679866 T2626 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_1,generation=1}
   [junit4]   2> 679866 T2626 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679867 T2626 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 679867 T2626 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 679867 T2626 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 679868 T2626 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 679868 T2626 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 679868 T2626 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 679869 T2626 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 679870 T2626 oass.SolrIndexSearcher.<init> Opening Searcher@1c86656 main
   [junit4]   2> 679870 T2626 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 679870 T2626 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 679870 T2626 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
   [junit4]   2> 679871 T2626 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_1,generation=1}
   [junit4]   2> 679871 T2626 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679871 T2626 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 679871 T2626 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 679871 T2627 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c86656 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 679872 T2591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 679872 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 679872 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> ASYNC  NEW_CORE C1314 name=collection1 org.apache.solr.core.SolrCore@1ee730e
   [junit4]   2> 679876 T2622 C1314 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 679877 T2622 C1314 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
   [junit4]   2> 679878 T2623 C1314 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 679879 T2623 C1314 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_2,generation=2}
   [junit4]   2> 679880 T2623 C1314 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 679880 T2623 C1314 oass.SolrIndexSearcher.<init> Opening Searcher@102e431 main
   [junit4]   2> 679881 T2623 C1314 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 679881 T2627 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@102e431 main{StandardDirectoryReader(segments_2:2:nrt)}
   [junit4]   2> 679881 T2623 C1314 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
   [junit4]   2> 679884 T2624 C1314 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
   [junit4]   2> 679885 T2591 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 679887 T2591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52677
   [junit4]   2> 679887 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 679888 T2591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 679888 T2591 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave
   [junit4]   2> 679888 T2591 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/'
   [junit4]   2> 679896 T2591 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/solr.xml
   [junit4]   2> 679897 T2591 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/solr.xml does not exist, using default configuration
   [junit4]   2> 679902 T2591 oasc.CoreContainer.<init> New CoreContainer 16880235
   [junit4]   2> 679902 T2591 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/]
   [junit4]   2> 679903 T2591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 679903 T2591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 679903 T2591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 679904 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 679904 T2591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 679904 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 679904 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 679905 T2591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 679905 T2591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 679905 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 679909 T2638 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1
   [junit4]   2> 679909 T2638 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/'
   [junit4]   2> 679933 T2638 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 679939 T2638 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 679940 T2638 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 679942 T2638 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 679945 T2638 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 679945 T2638 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 679945 T2638 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/
   [junit4]   2> 679945 T2638 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 679946 T2638 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data
   [junit4]   2> 679946 T2638 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index/
   [junit4]   2> 679946 T2638 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 679947 T2638 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index
   [junit4]   2> 679947 T2638 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b43f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8fffed),segFN=segments_1,generation=1}
   [junit4]   2> 679948 T2638 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 679949 T2638 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 679949 T2638 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 679949 T2638 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 679949 T2638 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 679949 T2638 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 679950 T2638 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 679951 T2638 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 679951 T2638 oass.SolrIndexSearcher.<init> Opening Searcher@1ded754 main
   [junit4]   2> 679952 T2638 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 679952 T2638 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 679952 T2638 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 679952 T2639 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ded754 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 679952 T2638 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 679953 T2591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 679953 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 679954 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> ASYNC  NEW_CORE C1315 name=collection1 org.apache.solr.core.SolrCore@1acbf2
   [junit4]   2> 679958 T2634 C1315 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
   [junit4]   2> ASYNC  NEW_CORE C1316 name=collection1 org.apache.solr.core.SolrCore@1ee730e
   [junit4]   2> 679960 T2625 C1316 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
   [junit4]   2> 679962 T2622 C1316 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
   [junit4]   2> 679964 T2623 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1
   [junit4]   2> 679964 T2623 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/'
   [junit4]   2> 679979 T2623 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 679985 T2623 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 679986 T2623 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 679988 T2623 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 679990 T2623 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 679991 T2623 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/
   [junit4]   2> 679991 T2623 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 679991 T2623 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index/
   [junit4]   2> 679992 T2623 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 679992 T2623 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 679993 T2623 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 679993 T2623 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 679993 T2623 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 679993 T2623 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 679994 T2623 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 679995 T2623 oass.SolrIndexSearcher.<init> Opening Searcher@d2262d main
   [junit4]   2> 679996 T2623 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 679996 T2623 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 679996 T2623 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
   [junit4]   2> 679997 T2623 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 679997 T2623 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
   [junit4]   2> 679997 T2623 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 679997 T2623 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
   [junit4]   2> 679997 T2642 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d2262d main{StandardDirectoryReader(segments_2:2:nrt)}
   [junit4]   2> 679998 T2623 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_2,generation=2}
   [junit4]   2> 679999 T2623 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 679999 T2623 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
   [junit4]   2> 679999 T2623 oass.SolrIndexSearcher.<init> Opening Searcher@1cac470 main
   [junit4]   2> 679999 T2642 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1cac470 main{StandardDirectoryReader(segments_2:2:nrt)}
   [junit4]   2> 680000 T2623 oasc.CoreContainer.registerCore replacing core: collection1
   [junit4]   2> 680000 T2623 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1ee730e
   [junit4]   2> 680000 T2623 oasu.DirectUpdateHandler2.close 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> 680001 T2623 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 680001 T2623 oass.SolrDispatchFilter.handleAdminRequest [admin] webapp=null path=/admin/cores params={action=reload&core=collection1&wt=javabin&qt=/admin/cores&version=2} status=0 QTime=38 
   [junit4]   2> ASYNC  NEW_CORE C1317 name=collection1 org.apache.solr.core.SolrCore@1e3c8b
   [junit4]   2> 680003 T2624 C1317 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&_trace=getIndexVersion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
   [junit4]   2> 680005 T2625 C1317 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=commits&_trace=getCommits&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
   [junit4]   2> 680007 T2622 C1317 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
   [junit4]   2> 680009 T2623 C1317 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
   [junit4]   2> 680011 T2624 C1317 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 680013 T2624 C1317 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1126970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc05),segFN=segments_3,generation=3}
   [junit4]   2> 680013 T2624 C1317 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 680014 T2624 C1317 oass.SolrIndexSearcher.<init> Opening Searcher@cdfba0 main
   [junit4]   2> 680014 T2624 C1317 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 680014 T2642 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cdfba0 main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C2)}
   [junit4]   2> 680014 T2624 C1317 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
   [junit4]   2> 680017 T2625 C1317 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
   [junit4]   2> 680018 T2635 C1315 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
   [junit4]   2> 680019 T2591 oash.TestReplicationHandler.rQuery Waiting for 2 docs
   [junit4]   2> 680121 T2636 C1315 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
   [junit4]   2> 680121 T2591 oash.TestReplicationHandler.rQuery Waiting for 2 docs
   [junit4]   2> 680223 T2637 C1315 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
   [junit4]   2> 680224 T2591 oash.TestReplicationHandler.rQuery Waiting for 2 docs
   [junit4]   2> 680300 T2623 C1317 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=1 
   [junit4]   2> 680300 T2640 oash.SnapPuller.fetchLatestIndex Master's generation: 3
   [junit4]   2> 680300 T2640 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
   [junit4]   2> 680300 T2640 oash.SnapPuller.fetchLatestIndex Starting replication process
   [junit4]   2> 680303 T2624 C1317 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1 
   [junit4]   2> 680303 T2640 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
   [junit4]   2> 680304 T2640 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index.20130809043223006
   [junit4]   2> 680304 T2640 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b4e138 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1eeb66a) fullCopy=false
   [junit4]   2> ASYNC  NEW_CORE C1318 name=collection1 org.apache.solr.core.SolrCore@1e3c8b
   [junit4]   2> 680316 T2625 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680319 T2622 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvd&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680321 T2623 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680324 T2624 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C1319 name=collection1 org.apache.solr.core.SolrCore@1acbf2
   [junit4]   2> 680326 T2634 C1319 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
   [junit4]   2> 680326 T2625 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.nvm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680326 T2591 oash.TestReplicationHandler.rQuery Waiting for 2 docs
   [junit4]   2> 680329 T2622 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=1 
   [junit4]   2> 680331 T2623 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680333 T2624 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680335 T2625 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680338 T2622 C1318 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
   [junit4]   2> 680339 T2640 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
   [junit4]   2> 680339 T2640 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
   [junit4]   2> 680339 T2640 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 680340 T2640 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
   [junit4]   2> 680341 T2640 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b43f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8fffed),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6b43f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8fffed),segFN=segments_3,generation=3}
   [junit4]   2> 680341 T2640 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 680341 T2640 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
   [junit4]   2> 680342 T2640 oass.SolrIndexSearcher.<init> Opening Searcher@961caa main
   [junit4]   2> 680342 T2639 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@961caa main{StandardDirectoryReader(segments_3:4:nrt _0(5.0):C2)}
   [junit4]   2> 680343 T2640 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index.20130809043223006 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index.20130809043223006;done=true>>]
   [junit4]   2> 680343 T2640 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index.20130809043223006
   [junit4]   2> 680429 T2636 C1319 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1 
   [junit4]   2> 680429 T2591 oas.SolrTestCaseJ4.tearDown ###Ending doTestReplicateAfterCoreReload
   [junit4]   2> 680451 T2591 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=4244256
   [junit4]   2> 680452 T2591 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1e3c8b
   [junit4]   2> 680452 T2591 oasu.DirectUpdateHandler2.close 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=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 680453 T2591 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 680453 T2591 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 680454 T2591 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 680455 T2591 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 680455 T2591 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 680455 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data;done=false>>]
   [junit4]   2> 680456 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data
   [junit4]   2> 680456 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index;done=false>>]
   [junit4]   2> 680456 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342196/master/collection1/data/index
   [junit4]   2> 680457 T2591 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 680530 T2591 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16880235
   [junit4]   2> 680530 T2591 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1acbf2
   [junit4]   2> 680531 T2591 oasu.DirectUpdateHandler2.close 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> 680531 T2591 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 680532 T2591 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 680532 T2591 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 680533 T2591 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 680533 T2591 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 680533 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data;done=false>>]
   [junit4]   2> 680534 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data
   [junit4]   2> 680534 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index;done=false>>]
   [junit4]   2> 680534 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008342291/slave/collection1/data/index
   [junit4]   2> 680535 T2591 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 680593 T2591 oas.SolrTestCaseJ4.setUp ###Starting doTestStopPoll
   [junit4]   2> 680596 T2591 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 680598 T2591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60445
   [junit4]   2> 680598 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 680598 T2591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 680599 T2591 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master
   [junit4]   2> 680599 T2591 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/'
   [junit4]   2> 680608 T2591 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/solr.xml
   [junit4]   2> 680609 T2591 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/solr.xml does not exist, using default configuration
   [junit4]   2> 680614 T2591 oasc.CoreContainer.<init> New CoreContainer 24334664
   [junit4]   2> 680615 T2591 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/]
   [junit4]   2> 680615 T2591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 680616 T2591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 680616 T2591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 680616 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 680616 T2591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 680617 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 680617 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 680617 T2591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 680617 T2591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 680618 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 680622 T2653 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1
   [junit4]   2> 680622 T2653 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/'
   [junit4]   2> 680639 T2653 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 680648 T2653 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 680648 T2653 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 680663 T2653 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 680667 T2653 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 680667 T2653 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 680668 T2653 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/data/
   [junit4]   2> 680668 T2653 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 680669 T2653 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/data
   [junit4]   2> 680669 T2653 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/data/index/
   [junit4]   2> 680669 T2653 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 680670 T2653 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343295/master/collection1/data/index
   [junit4]   2> 680671 T2653 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@14ea6b6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c39302),segFN=segments_1,generation=1}
   [junit4]   2> 680671 T2653 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 680672 T2653 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 680672 T2653 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 680673 T2653 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 680673 T2653 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 680673 T2653 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 680674 T2653 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 680676 T2653 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 680677 T2653 oass.SolrIndexSearcher.<init> Opening Searcher@1572fbd main
   [junit4]   2> 680677 T2653 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 680677 T2653 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 680677 T2653 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 680677 T2653 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 680678 T2654 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1572fbd main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 680678 T2653 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 680678 T2591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 680679 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 680679 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 680683 T2591 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 680685 T2591 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49956
   [junit4]   2> 680686 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 680686 T2591 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 680686 T2591 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave
   [junit4]   2> 680687 T2591 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/'
   [junit4]   2> 680694 T2591 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/solr.xml
   [junit4]   2> 680694 T2591 oasc.ConfigSolr.fromFile /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/solr.xml does not exist, using default configuration
   [junit4]   2> 680699 T2591 oasc.CoreContainer.<init> New CoreContainer 11047466
   [junit4]   2> 680700 T2591 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/]
   [junit4]   2> 680700 T2591 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 680701 T2591 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 680701 T2591 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 680701 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 680701 T2591 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 680702 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 680702 T2591 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 680702 T2591 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 680702 T2591 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 680703 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
   [junit4]   2> 680706 T2665 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1
   [junit4]   2> 680706 T2665 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/'
   [junit4]   2> 680721 T2665 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 680727 T2665 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 680728 T2665 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 680730 T2665 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 680732 T2665 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 680733 T2665 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 680733 T2665 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/data/
   [junit4]   2> 680733 T2665 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 680734 T2665 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/data
   [junit4]   2> 680734 T2665 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/data/index/
   [junit4]   2> 680734 T2665 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 680734 T2665 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008343383/slave/collection1/data/index
   [junit4]   2> 680735 T2665 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e18daf lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12403cb),segFN=segments_1,generation=1}
   [junit4]   2> 680735 T2665 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 680736 T2665 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 680737 T2665 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 680737 T2665 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 680737 T2665 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 680737 T2665 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 680737 T2665 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 680738 T2665 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 680739 T2665 oass.SolrIndexSearcher.<init> Opening Searcher@13ca6eb main
   [junit4]   2> 680739 T2665 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 680740 T2665 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 680740 T2665 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 680740 T2666 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13ca6eb main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 680740 T2665 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 680741 T2591 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0
   [junit4]   2> 680741 T2591 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 680742 T2591 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> ASYNC  NEW_CORE C1320 name=collection1 org.apache.solr.core.SolrCore@1f5971
   [junit4]   2> 680746 T2649 C1320 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
   [junit4]   2> 680749 T2650 C1320 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@14ea6b6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c39302),segFN=segments_1,generation=1}
   [junit4]   2> 680750 T2650 C1320 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 680751 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 3
   [junit4]   2> 680753 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
   [junit4]   2> 680755 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
   [junit4]   2> 680757 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
   [junit4]   2> 680758 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
   [junit4]   2> 680760 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
   [junit4]   2> 680762 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
   [junit4]   2> 680764 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
   [junit4]   2> 680766 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
   [junit4]   2> 680768 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
   [junit4]   2> 680769 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
   [junit4]   2> 680771 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
   [junit4]   2> 680772 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
   [junit4]   2> 680774 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 0
   [junit4]   2> 680776 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 0
   [junit4]   2> 680778 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 0
   [junit4]   2> 680780 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 0
   [junit4]   2> 680782 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
   [junit4]   2> 680783 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 0
   [junit4]   2> 680785 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
   [junit4]   2> 680787 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 0
   [junit4]   2> 680789 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 0
   [junit4]   2> 680791 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
   [junit4]   2> 680793 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 0
   [junit4]   2> 680795 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 1
   [junit4]   2> 680796 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 0
   [junit4]   2> 680798 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 0
   [junit4]   2> 680800 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 0
   [junit4]   2> 680801 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 0
   [junit4]   2> 680803 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 0
   [junit4]   2> 680805 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 0
   [junit4]   2> 680807 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 0
   [junit4]   2> 680809 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 1
   [junit4]   2> 680810 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 0
   [junit4]   2> 680812 T2652 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 0
   [junit4]   2> 680814 T2649 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 0
   [junit4]   2> 680815 T2650 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
   [junit4]   2> 680817 T2651 C1320 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 

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

unk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427159/master/collection1/data/index;done=false>>]
   [junit4]   2> 767398 T3260 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 767398 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427159/master/collection1/data/index
   [junit4]   2> 767398 T3260 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 767398 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427159/master/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427159/master/collection1/data;done=false>>]
   [junit4]   2> 767399 T3260 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 767399 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427159/master/collection1/data
   [junit4]   2> 767399 T3261 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8317e5 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
   [junit4]   2> 767399 T3260 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
   [junit4]   2> 767400 T3260 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 767400 T3260 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
   [junit4]   2> 767399 T2591 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 767402 T3260 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ef241a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f3477),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1ef241a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f3477),segFN=segments_2,generation=2}
   [junit4]   2> 767402 T3260 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 767402 T3260 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
   [junit4]   2> 767403 T3260 oass.SolrIndexSearcher.<init> Opening Searcher@16657fc main
   [junit4]   2> 767403 T3260 oasc.CoreContainer.registerCore replacing core: collection1
   [junit4]   2> 767403 T3261 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@16657fc main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
   [junit4]   2> 767403 T3260 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@d88642
   [junit4]   2> 767404 T3260 oasu.DirectUpdateHandler2.close 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> 767404 T3260 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 767473 T2591 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15697500
   [junit4]   2> 767474 T2591 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@18f7562
   [junit4]   2> 767475 T2591 oasu.DirectUpdateHandler2.close 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> 767475 T2591 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 767476 T2591 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 767476 T2591 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 767477 T2591 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 767478 T2591 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 767478 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427262/slave/collection1/data/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427262/slave/collection1/data/index;done=false>>]
   [junit4]   2> 767479 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427262/slave/collection1/data/index
   [junit4]   2> 767479 T2591 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427262/slave/collection1/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427262/slave/collection1/data;done=false>>]
   [junit4]   2> 767480 T2591 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J0/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376008427262/slave/collection1/data
   [junit4]   2> 767480 T2591 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 767537 T2591 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=bg, timezone=Europe/Samara
   [junit4]   2> NOTE: Linux 3.2.0-51-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=1,free=63073504,total=249257984
   [junit4]   2> NOTE: All tests run in this JVM: [TestRecoveryHdfs, DistributedSpellCheckComponentTest, DocValuesTest, TestLMJelinekMercerSimilarityFactory, RegexBoostProcessorTest, TestSolrQueryParserDefaultOperatorResource, TestSystemIdResolver, BlockDirectoryTest, TestSolrXml, AutoCommitTest, SuggesterTest, TestPseudoReturnFields, SolrInfoMBeanTest, SOLR749Test, TestPostingsSolrHighlighter, UnloadDistributedZkTest, DistributedQueryElevationComponentTest, TestCharFilters, SampleTest, ResponseLogComponentTest, MoreLikeThisHandlerTest, DOMUtilTest, TimeZoneUtilsTest, SearchHandlerTest, SpellPossibilityIteratorTest, TestSolrJ, TestPerFieldSimilarity, UpdateRequestProcessorFactoryTest, BinaryUpdateRequestHandlerTest, ChaosMonkeyNothingIsSafeTest, SynonymTokenizerTest, TestGroupingSearch, SuggesterFSTTest, HdfsDirectoryTest, ExternalFileFieldSortTest, TestFunctionQuery, AnalysisAfterCoreReloadTest, SystemInfoHandlerTest, TestCoreContainer, CoreMergeIndexesAdminHandlerTest, FileBasedSpellCheckerTest, SyncSliceTest, HdfsChaosMonkeySafeLeaderTest, OverseerCollectionProcessorTest, PrimitiveFieldTypeTest, TestExtendedDismaxParser, FieldMutatingUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SolrCoreTest, ZkCLITest, TestElisionMultitermQuery, TestBadConfig, TestLFUCache, TestUniqueKeyFieldResource, TestShardHandlerFactory, EchoParamsTest, DateFieldTest, TestValueSourceCache, SolrIndexSplitterTest, SolrTestCaseJ4Test, TestMaxScoreQueryParser, BasicFunctionalityTest, SoftAutoCommitTest, TestSolrQueryParserResource, OverseerTest, RecoveryZkTest, TestFastLRUCache, TestRTGBase, TestStressVersions, TestFieldResource, TestPHPSerializedResponseWriter, TestDocumentBuilder, TestLRUCache, TestQueryUtils, DirectUpdateHandlerOptimizeTest, DocumentBuilderTest, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, ShardRoutingTest, TestReplicationHandler]
   [junit4] Completed on J0 in 88.07s, 14 tests, 2 failures <<< FAILURES!

[...truncated 472 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:389: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:369: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:181: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1230: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:873: There were test failures: 318 suites, 1345 tests, 2 failures, 39 ignored (6 assumptions)

Total time: 40 minutes 8 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b99 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message