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-4.x-Linux (32bit/jdk1.6.0_43) - Build # 4951 - Failure!
Date Wed, 03 Apr 2013 21:09:23 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/4951/
Java: 32bit/jdk1.6.0_43 -client -XX:+UseParallelGC

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

Error Message:
expected:<1365023147967> but was:<1365023147955>

Stack Trace:
java.lang.AssertionError: expected:<1365023147967> but was:<1365023147955>
	at __randomizedtesting.SeedInfo.seed([A1C2E3F8E8C8796B:7A69E33EEDE010D8]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:147)
	at org.apache.solr.handler.TestReplicationHandler.assertVersions(TestReplicationHandler.java:871)
	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:735)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
	at java.lang.reflect.Method.invoke(Method.java:597)
	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:662)




Build Log:
[...truncated 8803 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.691; org.apache.solr.SolrTestCaseJ4; ###Starting doTestDetails
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.694; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.697; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:52036
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.697; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.698; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.698; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.699; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.699; org.apache.solr.core.CoreContainer; New CoreContainer 30655372
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.699; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.700; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.700; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.719; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.719; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.719; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.720; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.720; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.720; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.721; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.721; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.722; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.722; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.729; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.744; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.745; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.792; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.831; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.832; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.834; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.847; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.848; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.848; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.848; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.848; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.849; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.849; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:13.849; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.850; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.850; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.850; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.851; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.851; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.852; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.852; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.852; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.852; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.854; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.856; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@13ee37c main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.857; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.857; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.857; org.apache.solr.handler.ReplicationHandler; Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.857; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.858; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.858; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@13ee37c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.858; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.859; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.859; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.864; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.866; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:45482
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.867; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.868; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.868; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.868; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.869; org.apache.solr.core.CoreContainer; New CoreContainer 19101106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.869; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.870; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.870; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.887; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.887; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.888; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.888; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.889; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.889; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.889; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.890; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.890; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.891; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.897; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.914; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.914; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.947; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.983; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.984; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.986; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.998; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.999; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:13.999; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.000; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.000; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.000; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.001; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:14.001; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.001; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.002; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c6a257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19aba5c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.002; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.003; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.003; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.003; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.004; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.004; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.004; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.005; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.007; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@9468cc main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.008; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.008; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.010; org.apache.solr.handler.SnapPuller; Poll Scheduled at an interval of 1000ms
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.010; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.010; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@9468cc main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.011; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.012; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.013; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.023; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.027; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.037; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.039; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.042; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.043; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.045; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 4
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.047; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.049; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.050; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.052; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@14d6740 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.053; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.053; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@14d6740 main{StandardDirectoryReader(segments_2:3:nrt _0(4.3):C1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.054; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.059; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:52036/solr/replication&command=fetchindex} status=0 QTime=3 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:14.059; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.060; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.062; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.063; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.063; org.apache.solr.handler.SnapPuller; Slave's generation: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.064; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.063; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.066; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.069; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.069; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.071; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 10
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.072; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.072; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index.20130403170514072
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.073; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1fa20d5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@81be83) fullCopy=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.075; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_3,generation=3,filenames=[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.076; org.apache.solr.core.SolrDeletionPolicy; newest commit = 3[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.076; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.077; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@1c11af6 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.078; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.078; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@1c11af6 main{StandardDirectoryReader(segments_3:5:nrt _0(4.3):C1 _1(4.3):C1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.080; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.080; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.081; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:52036/solr/replication&command=fetchindex} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.084; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.086; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.088; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.088; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.092; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.092; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.095; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.095; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.097; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=3
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_3,generation=3,filenames=[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e3cdac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@194a738),segFN=segments_4,generation=4,filenames=[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _2_Lucene41_0.tip, _1.fnm, _2_nrm.cfe, _0.si, _2_nrm.cfs, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _1_nrm.cfs, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _0_nrm.cfs, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _1_nrm.cfe, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0_nrm.cfe, _0.fdx, _0.fdt, segments_4]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.098; org.apache.solr.core.SolrDeletionPolicy; newest commit = 4[_2.si, _1_Lucene41_0.doc, _2_Lucene41_0.tim, _2_Lucene41_0.tip, _1.fnm, _2_nrm.cfe, _0.si, _2_nrm.cfs, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _1_nrm.cfs, _0.fnm, _1_Lucene41_0.tim, _2.fdt, _1_Lucene41_0.tip, _0_nrm.cfs, _2_Lucene41_0.doc, _2.fdx, _2.fnm, _1_nrm.cfe, _1.fdx, _0_Lucene41_0.doc, _1.fdt, _1.si, _0_nrm.cfe, _0.fdx, _0.fdt, segments_4]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.100; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.100; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@b78433 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.101; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.101; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@b78433 main{StandardDirectoryReader(segments_4:7:nrt _0(4.3):C1 _1(4.3):C1 _2(4.3):C1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.102; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.104; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:52036/solr/replication&command=fetchindex} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.105; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.109; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.110; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.112; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.114; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:38847
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.115; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.116; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.117; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.118; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.118; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.119; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.120; org.apache.solr.core.CoreContainer; New CoreContainer 29849400
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.119; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.121; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.122; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.122; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c6a257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19aba5c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@c6a257 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19aba5c),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.123; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.123; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.124; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.126; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@75dd6d main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.126; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@75dd6d main{StandardDirectoryReader(segments_2:3:nrt _0(4.3):C1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.127; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index.20130403170514072 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index.20130403170514072;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.127; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index.20130403170514072
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.128; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index.20130403170514072
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.152; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.152; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.153; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.153; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.153; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.154; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.154; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.155; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.155; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.155; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.162; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.183; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.184; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.222; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.258; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.258; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.260; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.273; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.274; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.274; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.274; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.274; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.275; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.275; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:14.275; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.276; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.276; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@176ca7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e33d1a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.277; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.277; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.277; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.278; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.278; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.278; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.278; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.280; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.282; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@155c11e main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.282; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.282; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:14.282; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.283; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.283; org.apache.solr.handler.ReplicationHandler; Replication enabled for following config files: schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.283; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.283; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.283; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@155c11e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.284; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.285; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.286; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.303; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.304; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.327; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=29849400
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.331; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@e3227b
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.332; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.333; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.333; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.334; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.335; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.336; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.337; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.338; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.339; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.340; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114105/repeater/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.342; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.397; org.apache.solr.SolrTestCaseJ4; ###Ending doTestDetails
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.420; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=30655372
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.424; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@c5751b
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.425; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.426; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.427; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.428; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.429; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.430; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.431; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.432; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.433; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.434; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113691/master/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.436; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.510; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=19101106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.513; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@695f94
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.514; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.515; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.516; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.517; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.518; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.519; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.520; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.521; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.522; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.522; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023113862/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.524; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.588; org.apache.solr.SolrTestCaseJ4; ###Starting doTestRepeater
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.594; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.598; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:33994
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.600; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.601; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.602; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.602; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.603; org.apache.solr.core.CoreContainer; New CoreContainer 12140486
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.604; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.605; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.606; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.626; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.627; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.627; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.627; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.628; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.628; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.628; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.629; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.629; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.630; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.636; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.652; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.652; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.686; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.722; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.723; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.725; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.738; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.739; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.739; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.739; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.739; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.740; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.740; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:14.740; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.741; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.742; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7a81d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cfa77a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.742; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.743; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.743; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.743; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.743; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.744; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.744; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.746; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.748; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@154f9b1 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.748; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.748; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.749; org.apache.solr.handler.ReplicationHandler; Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.749; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.749; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.749; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@154f9b1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.750; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.750; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.751; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.759; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.762; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:43787
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.763; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.763; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.764; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.765; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.765; org.apache.solr.core.CoreContainer; New CoreContainer 7022918
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.766; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.767; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.767; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.784; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.785; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.785; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.786; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.786; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.786; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.787; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.787; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.787; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.788; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.795; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.812; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.813; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.846; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.893; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.894; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.896; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.909; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.910; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.910; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.910; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.911; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.911; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.911; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:14.911; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.912; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.913; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@71c4d9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d2d0e0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.913; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.914; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.914; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.914; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.914; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.914; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.915; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.916; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.918; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@112889e main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.918; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.919; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.919; org.apache.solr.handler.SnapPuller; Poll Scheduled at an interval of 1000ms
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.919; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.919; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@112889e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.920; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.920; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.921; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.946; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=7022918
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.948; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@cf3f7e
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.949; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.949; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.950; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.950; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.951; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.951; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.952; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.952; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.952; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.953; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:14.953; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.006; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.009; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:33975
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.010; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.010; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.011; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.011; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.011; org.apache.solr.core.CoreContainer; New CoreContainer 10452921
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.012; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.012; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.013; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.028; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.029; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.029; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.030; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.030; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.030; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.031; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.031; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.031; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.032; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.038; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.054; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.054; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.082; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.120; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.121; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.123; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.136; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.137; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.137; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.138; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.138; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.138; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.139; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.139; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.139; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.140; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b580e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2834b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.140; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.141; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.141; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.141; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.141; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.142; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.142; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.143; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.146; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@1ececdd main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.146; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.146; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.146; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.147; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.147; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@1ececdd main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.147; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.148; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.148; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.154; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.158; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:53987
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.159; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.159; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.160; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.161; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.161; org.apache.solr.core.CoreContainer; New CoreContainer 16476900
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.162; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.163; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.163; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.179; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.179; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.180; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.180; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.181; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.181; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.181; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.182; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.182; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.182; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.189; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.204; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.205; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.233; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.270; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.271; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.274; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.286; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.287; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.287; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.287; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.288; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.288; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.288; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.288; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.289; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.290; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16d696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d51465),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.290; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.291; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.291; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.291; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.291; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.292; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.292; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.293; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.295; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@59e0b8 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.295; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.296; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.296; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.296; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.296; org.apache.solr.handler.ReplicationHandler; Replication enabled for following config files: schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.297; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.297; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.297; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@59e0b8 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.298; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.298; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.298; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.306; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7a81d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cfa77a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.306; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.307; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.309; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.311; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.313; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.314; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7a81d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cfa77a),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7a81d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cfa77a),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.314; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.315; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@f098e6 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.316; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.316; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@f098e6 main{StandardDirectoryReader(segments_2:3:nrt _0(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.316; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 3
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.327; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:33994/solr/replication&command=fetchindex} status=0 QTime=0 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.327; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.328; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.328; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.329; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.329; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.330; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.330; org.apache.solr.handler.SnapPuller; Slave's generation: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.330; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.332; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.333; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 10
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.333; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515333
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.334; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@8d43e0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@635ecd) fullCopy=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.336; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.338; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.341; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.343; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.346; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.348; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.350; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.353; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.355; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.358; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.359; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.360; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.360; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.360; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16d696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d51465),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16d696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d51465),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.361; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.361; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.361; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@f66f50 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.361; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@f66f50 main{StandardDirectoryReader(segments_2:3:nrt _0(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.362; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515333 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515333;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.362; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515333
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.362; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515333
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.430; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.432; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.538; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:53987/solr/replication&command=fetchindex} status=0 QTime=1 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.538; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.538; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.538; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.539; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.540; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.540; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.540; org.apache.solr.handler.SnapPuller; Slave's generation: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.541; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.543; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.543; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 10
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.544; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170515543
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.544; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@c7b60a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1807b23) fullCopy=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.546; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.549; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.551; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.554; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.556; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.559; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.561; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.564; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.566; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.569; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.569; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.571; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.571; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.571; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b580e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2834b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b580e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2834b),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.572; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.572; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.573; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@b76e23 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.573; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@b76e23 main{StandardDirectoryReader(segments_2:3:nrt _0(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.573; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170515543 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170515543;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.573; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170515543
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.574; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170515543
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.641; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.643; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.746; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.748; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.750; org.apache.solr.handler.ReplicationHandler; Exception while invoking 'details' method for replication on master 
[junit4:junit4]   1> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:409)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]   1> 	at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1541)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:602)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:218)
[junit4:junit4]   1> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1816)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:639)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> Caused by: org.apache.http.conn.HttpHostConnectException: Connection to http://127.0.0.1 refused
[junit4:junit4]   1> 	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:190)
[junit4:junit4]   1> 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
[junit4:junit4]   1> 	at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
[junit4:junit4]   1> 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:353)
[junit4:junit4]   1> 	... 34 more
[junit4:junit4]   1> Caused by: java.net.ConnectException: Connection refused
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:351)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:213)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:200)
[junit4:junit4]   1> 	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit4:junit4]   1> 	at java.net.Socket.connect(Socket.java:529)
[junit4:junit4]   1> 	at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
[junit4:junit4]   1> 	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
[junit4:junit4]   1> 	... 41 more
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.752; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.754; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.756; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.758; org.apache.solr.handler.ReplicationHandler; Exception while invoking 'details' method for replication on master 
[junit4:junit4]   1> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:409)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]   1> 	at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1541)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:602)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:218)
[junit4:junit4]   1> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1816)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:639)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> Caused by: org.apache.http.conn.HttpHostConnectException: Connection to http://127.0.0.1 refused
[junit4:junit4]   1> 	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:190)
[junit4:junit4]   1> 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
[junit4:junit4]   1> 	at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
[junit4:junit4]   1> 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:353)
[junit4:junit4]   1> 	... 34 more
[junit4:junit4]   1> Caused by: java.net.ConnectException: Connection refused
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:351)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:213)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:200)
[junit4:junit4]   1> 	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit4:junit4]   1> 	at java.net.Socket.connect(Socket.java:529)
[junit4:junit4]   1> 	at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
[junit4:junit4]   1> 	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
[junit4:junit4]   1> 	... 41 more
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.760; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.762; org.apache.solr.handler.ReplicationHandler; Exception while invoking 'details' method for replication on master 
[junit4:junit4]   1> org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:TEST_PORT/solr
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:409)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]   1> 	at org.apache.solr.handler.SnapPuller.getDetails(SnapPuller.java:1541)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:602)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:218)
[junit4:junit4]   1> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1816)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:639)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:141)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1307)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:453)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:275)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1074)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:382)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1006)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.Server.handle(Server.java:365)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:485)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:926)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:988)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:635)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> Caused by: org.apache.http.conn.HttpHostConnectException: Connection to http://127.0.0.1 refused
[junit4:junit4]   1> 	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:190)
[junit4:junit4]   1> 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.open(ManagedClientConnectionImpl.java:294)
[junit4:junit4]   1> 	at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:645)
[junit4:junit4]   1> 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:480)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4]   1> 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:353)
[junit4:junit4]   1> 	... 34 more
[junit4:junit4]   1> Caused by: java.net.ConnectException: Connection refused
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.socketConnect(Native Method)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:351)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:213)
[junit4:junit4]   1> 	at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:200)
[junit4:junit4]   1> 	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
[junit4:junit4]   1> 	at java.net.Socket.connect(Socket.java:529)
[junit4:junit4]   1> 	at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:127)
[junit4:junit4]   1> 	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:180)
[junit4:junit4]   1> 	... 41 more
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.763; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.767; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.768; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.770; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.771; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.774; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.775; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.777; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.779; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.781; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.783; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16d696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d51465),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@16d696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d51465),segFN=segments_3,generation=3,filenames=[_1.fdx, _1_nrm.cfs, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, segments_3, _1_nrm.cfe]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.783; org.apache.solr.core.SolrDeletionPolicy; newest commit = 3[_1.fdx, _1_nrm.cfs, _1_Lucene41_0.doc, _1.fdt, _1.si, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _1.fnm, segments_3, _1_nrm.cfe]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.784; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@4d9ac0 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.785; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.785; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@4d9ac0 main{StandardDirectoryReader(segments_3:7:nrt _1(4.3):C4)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.785; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.786; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:33994/solr/replication&command=fetchindex} status=0 QTime=0 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.786; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.787; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.787; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.789; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=4 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.789; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.790; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.790; org.apache.solr.handler.SnapPuller; Slave's generation: 3
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.790; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.792; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.793; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 10
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.793; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515793
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.794; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@90408c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7dacbf) fullCopy=true
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.796; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.798; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.801; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.803; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.806; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.808; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.811; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.813; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.816; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.818; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.819; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.820; org.apache.solr.handler.SnapPuller; New index installed. Updating index properties... index=index.20130403170515793
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.820; org.apache.solr.handler.SnapPuller; removing old index directory MockDirWrapper(org.apache.lucene.store.RAMDirectory@16d696 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d51465)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.821; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.821; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.821; org.apache.solr.update.DefaultSolrCoreState; Rollback old IndexWriter... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.822; org.apache.solr.core.SolrCore; New index directory detected: old=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index/ new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515793
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.822; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@90408c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7dacbf),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.822; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.823; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.823; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@19dc0d main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.823; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@19dc0d main{StandardDirectoryReader(segments_2:3:nrt _0(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.824; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.824; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.824; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.890; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.892; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.993; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:53987/solr/replication&command=fetchindex} status=0 QTime=0 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:15.993; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.994; org.apache.solr.handler.TestReplicationHandler; Waiting for 3 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.995; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.997; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.997; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:15.998; org.apache.solr.handler.SnapPuller; Slave in sync with master.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.100; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.102; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.104; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.105; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.107; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7a81d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cfa77a),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@f7a81d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cfa77a),segFN=segments_3,generation=3,filenames=[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.107; org.apache.solr.core.SolrDeletionPolicy; newest commit = 3[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.108; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@2a0b7b main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.108; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.108; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@2a0b7b main{StandardDirectoryReader(segments_3:5:nrt _0(4.3):C3 _1(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.109; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.110; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:33994/solr/replication&command=fetchindex} status=0 QTime=0 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:16.110; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.111; org.apache.solr.handler.TestReplicationHandler; Waiting for 6 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.111; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.113; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.113; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.114; org.apache.solr.handler.SnapPuller; Master's generation: 3
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.114; org.apache.solr.handler.SnapPuller; Slave's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.114; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.116; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.117; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 19
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.117; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170516117
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.117; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@68a79a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d39393) fullCopy=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.119; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.122; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.123; org.apache.solr.handler.SnapPuller; Skipping download for _0.fnm because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.124; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.127; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.128; org.apache.solr.handler.SnapPuller; Skipping download for _0_nrm.cfs because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.129; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.132; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.134; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.135; org.apache.solr.handler.SnapPuller; Skipping download for _0_Lucene41_0.doc because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.137; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.139; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.140; org.apache.solr.handler.SnapPuller; Skipping download for _0_nrm.cfe because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.140; org.apache.solr.handler.SnapPuller; Skipping download for _0.fdx because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.140; org.apache.solr.handler.SnapPuller; Skipping download for _0.si because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.140; org.apache.solr.handler.SnapPuller; Skipping download for _0_Lucene41_0.tim because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.140; org.apache.solr.handler.SnapPuller; Skipping download for _0.fdt because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.141; org.apache.solr.handler.SnapPuller; Skipping download for _0_Lucene41_0.tip because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.142; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.143; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.144; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.144; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.144; org.apache.solr.update.DefaultSolrCoreState; Closing old IndexWriter... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.145; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   1> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@90408c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7dacbf),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> 	commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@90408c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7dacbf),segFN=segments_3,generation=3,filenames=[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.145; org.apache.solr.core.SolrDeletionPolicy; newest commit = 3[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.146; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.146; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@42b771 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.146; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@42b771 main{StandardDirectoryReader(segments_3:5:nrt _0(4.3):C3 _1(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.147; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170516117 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170516117;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.147; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170516117
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.147; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170516117
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.213; org.apache.solr.handler.TestReplicationHandler; Waiting for 6 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.215; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=6 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.317; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:53987/solr/replication&command=fetchindex} status=0 QTime=1 
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:16.317; org.apache.solr.handler.SnapPuller; 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.318; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.318; org.apache.solr.handler.TestReplicationHandler; Waiting for 6 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.319; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.319; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.320; org.apache.solr.handler.SnapPuller; Master's generation: 3
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.320; org.apache.solr.handler.SnapPuller; Slave's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.320; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.323; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.323; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 19
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.324; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170516324
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.324; org.apache.solr.handler.SnapPuller; Starting download to BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@cf8ea3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@9d77f4) fullCopy=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.326; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.329; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.doc&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.330; org.apache.solr.handler.SnapPuller; Skipping download for _0.fnm because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.332; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.tim&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.334; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_Lucene41_0.tip&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.335; org.apache.solr.handler.SnapPuller; Skipping download for _0_nrm.cfs because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.337; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.339; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1_nrm.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.342; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.343; org.apache.solr.handler.SnapPuller; Skipping download for _0_Lucene41_0.doc because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.344; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.347; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.348; org.apache.solr.handler.SnapPuller; Skipping download for _0_nrm.cfe because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.348; org.apache.solr.handler.SnapPuller; Skipping download for _0.fdx because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.348; org.apache.solr.handler.SnapPuller; Skipping download for _0.si because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.348; org.apache.solr.handler.SnapPuller; Skipping download for _0_Lucene41_0.tim because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.348; org.apache.solr.handler.SnapPuller; Skipping download for _0_Lucene41_0.tip because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.349; org.apache.solr.handler.SnapPuller; Skipping download for _0.fdt because it already exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.350; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.351; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.352; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.352; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.352; org.apache.solr.update.DefaultSolrCoreState; Closing old IndexWriter... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.353; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b580e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2834b),segFN=segments_2,generation=2,filenames=[_0.fnm, _0_Lucene41_0.doc, _0_nrm.cfe, segments_2, _0.fdx, _0_nrm.cfs, _0.si, _0_Lucene41_0.tim, _0_Lucene41_0.tip, _0.fdt]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1b580e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a2834b),segFN=segments_3,generation=3,filenames=[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.354; org.apache.solr.core.SolrDeletionPolicy; newest commit = 3[_1_nrm.cfs, _1_Lucene41_0.doc, _0.fnm, _1_Lucene41_0.tim, _1_Lucene41_0.tip, _0_nrm.cfs, _1.fnm, _1_nrm.cfe, _1.fdx, _1.fdt, _0_Lucene41_0.doc, _1.si, _0_nrm.cfe, _0.fdx, _0.si, _0_Lucene41_0.tim, segments_3, _0.fdt, _0_Lucene41_0.tip]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.354; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.354; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@1c4d7be main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.355; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@1c4d7be main{StandardDirectoryReader(segments_3:5:nrt _0(4.3):C3 _1(4.3):C3)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.355; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170516324 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170516324;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.355; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170516324
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.355; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index.20130403170516324
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.421; org.apache.solr.handler.TestReplicationHandler; Waiting for 6 docs
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.423; org.apache.solr.core.SolrCore; [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=6 status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.545; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=16476900
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.547; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@481226
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.547; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.548; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.548; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.549; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.550; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.550; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.550; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515793 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515793;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.551; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data/index.20130403170515793
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.552; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.552; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023115151/repeater/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.553; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.606; org.apache.solr.SolrTestCaseJ4; ###Ending doTestRepeater
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.628; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=12140486
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.630; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@cffc15
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.631; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=6,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.631; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.631; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.632; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.632; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.633; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.633; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.634; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.634; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.634; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114589/master/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.635; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.709; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=10452921
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.711; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1a01996
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.711; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.712; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.712; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.713; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.713; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.714; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.714; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.715; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.715; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.715; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023114755/slave/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.716; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.773; org.apache.solr.SolrTestCaseJ4; ###Starting doTestReplicateAfterWrite2Slave
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.776; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.777; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:59442
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.778; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.778; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.779; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.779; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.780; org.apache.solr.core.CoreContainer; New CoreContainer 27957833
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.780; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.780; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.781; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.800; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.800; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.801; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.801; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.801; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.802; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.802; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.803; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.803; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.803; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.810; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.831; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.832; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.859; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.895; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.896; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.898; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.911; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.913; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.913; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.913; org.apache.solr.core.SolrCore; JMX monitoring not detected for core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.913; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.914; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.914; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:05:16.914; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.915; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116773/master/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.915; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@43e528 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@116e51),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.916; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.916; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.916; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.917; org.apache.solr.core.RequestHandlers; created defaults: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.917; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.917; org.apache.solr.core.RequestHandlers; created lazy: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.917; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.920; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.922; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@16c8cd7 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.922; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.922; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.922; org.apache.solr.handler.ReplicationHandler; Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.923; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.923; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.923; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@16c8cd7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.924; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.924; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.925; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.929; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.931; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:33487
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.932; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.932; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.933; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116927/slave
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.933; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116927/slave/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.933; org.apache.solr.core.CoreContainer; New CoreContainer 21067897
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.934; org.apache.solr.core.CoreContainer$Initializer; no solr.xml found. using default old-style solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.934; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116927/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.934; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023116927/slave/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.950; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.951; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.951; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.952; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.952; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.952; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.953; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:16.953; org.apache.solr.handler.component.HttpShardHandl

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

ller.fetchLatestIndex(SnapPuller.java:396)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:281)
[junit4:junit4]   1> 	at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:186)
[junit4:junit4]   1> 
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.211; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=11188088
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.212; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@ca4840
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.213; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=425,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.213; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.213; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.214; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.214; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.215; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.215; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145332/master/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145332/master/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.216; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145332/master/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.216; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145332/master/collection1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145332/master/collection1/data/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.216; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145332/master/collection1/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.217; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.288; org.apache.solr.core.CoreContainer; Shutting down CoreContainer instance=30648695
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.291; org.apache.solr.core.SolrCore; [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@16ed671
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.291; org.apache.solr.update.DirectUpdateHandler2; closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=9,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.292; org.apache.solr.update.SolrCoreState; Closing SolrCoreState
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.293; org.apache.solr.update.DefaultSolrCoreState; SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.293; org.apache.solr.update.DefaultSolrCoreState; closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.295; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.295; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.296; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145500/slave/collection1/data [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145500/slave/collection1/data;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.296; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145500/slave/collection1/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.297; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145500/slave/collection1/data/index.20130403170547736 [CachedDir<<refCount=0;path=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145500/slave/collection1/data/index.20130403170547736;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.298; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1365023145500/slave/collection1/data/index.20130403170547736
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.311; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestReplicationHandler -Dtests.method=doTestStressReplication -Dtests.seed=A1C2E3F8E8C8796B -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_CS -Dtests.timezone=America/Manaus -Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE 3.05s J1 | TestReplicationHandler.doTestStressReplication <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: expected:<1365023147967> but was:<1365023147955>
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([A1C2E3F8E8C8796B:7A69E33EEDE010D8]:0)
[junit4:junit4]    > 	at org.apache.solr.handler.TestReplicationHandler.assertVersions(TestReplicationHandler.java:871)
[junit4:junit4]    > 	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:735)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:662)
[junit4:junit4]   1> INFO  - 2013-04-03 21:05:48.373; org.apache.solr.SolrTestCaseJ4; ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=sr_CS, timezone=America/Manaus
[junit4:junit4]   2> NOTE: Linux 3.2.0-39-generic i386/Sun Microsystems Inc. 1.6.0_43 (32-bit)/cpus=8,threads=1,free=93881560,total=264634368
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestIndexingPerformance, BasicDistributedZk2Test, TestRecovery, BadIndexSchemaTest, TestSuggestSpellingConverter, SpellCheckCollatorTest, LeaderElectionIntegrationTest, TestCoreDiscovery, TestRandomFaceting, TestStressLucene, BadCopyFieldTest, TestElisionMultitermQuery, AliasIntegrationTest, TestSystemIdResolver, TestBM25SimilarityFactory, NotRequiredUniqueKeyTest, DisMaxRequestHandlerTest, DebugComponentTest, TestZkChroot, TestSchemaVersionResource, UniqFieldsUpdateProcessorFactoryTest, RequestHandlersTest, TestCodecSupport, TestRangeQuery, HardAutoCommitTest, CollectionsAPIDistributedZkTest, TestDynamicFieldResource, DefaultValueUpdateProcessorTest, TestCollationField, TestBinaryField, TestQuerySenderNoQuery, TestStressReorder, HighlighterConfigTest, SuggesterWFSTTest, TestWordDelimiterFilterFactory, XmlUpdateRequestHandlerTest, TestJmxIntegration, SortByFunctionTest, TestUpdate, BasicZkTest, TestSolrQueryParserResource, UpdateParamsTest, TestDistributedGrouping, TestQueryTypes, TestPostingsSolrHighlighter, TestFiltering, EchoParamsTest, HighlighterTest, TestDistributedSearch, TestPartialUpdateDeduplication, OutputWriterTest, CopyFieldTest, OpenExchangeRatesOrgProviderTest, SolrTestCaseJ4Test, TestPropInject, DistanceFunctionTest, SpellPossibilityIteratorTest, ZkSolrClientTest, CurrencyFieldXmlFileTest, TestCSVLoader, TestCloudManagedSchema, StatsComponentTest, IndexSchemaRuntimeFieldTest, NoCacheHeaderTest, SolrCmdDistributorTest, ClusterStateTest, FullSolrCloudDistribCmdsTest, TestCSVResponseWriter, SuggesterTSTTest, IndexBasedSpellCheckerTest, OpenCloseCoreStressTest, RecoveryZkTest, TestReplicationHandler]
[junit4:junit4] Completed on J1 in 34.72s, 12 tests, 1 failure <<< FAILURES!

[...truncated 417 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:381: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:361: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:448: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1234: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:878: There were test failures: 285 suites, 1175 tests, 1 failure, 13 ignored (7 assumptions)

Total time: 47 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.6.0_43 -client -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message