lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0-ea-b102) - Build # 3215 - Failure!
Date Sun, 01 Sep 2013 17:34:18 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3215/
Java: 64bit/jdk1.8.0-ea-b102 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

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

Error Message:
timed out waiting for collection1 startAt time to exceed: Mon Sep 02 01:25:14 CST 2013

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Mon Sep 02 01:25:14 CST 2013
	at __randomizedtesting.SeedInfo.seed([2C8285903C0E03D9:DBF16BC8FAE6AC3F]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1514)
	at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1263)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:491)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:724)


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

Error Message:
timed out waiting for collection1 startAt time to exceed: Mon Sep 02 01:25:48 CST 2013

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Mon Sep 02 01:25:48 CST 2013
	at __randomizedtesting.SeedInfo.seed([2C8285903C0E03D9:F729855639266A6A]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1514)
	at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:818)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:491)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:724)




Build Log:
[...truncated 9973 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> 1826851 T5380 oas.SolrTestCaseJ4.setUp ###Starting testNoWriter
   [junit4]   2> 1826874 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1826914 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53633
   [junit4]   2> 1826915 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1826916 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1826916 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master
   [junit4]   2> 1826917 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\'
   [junit4]   2> 1826966 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\solr.xml
   [junit4]   2> 1826992 T5380 oasc.CoreContainer.<init> New CoreContainer 1751065234
   [junit4]   2> 1826994 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\]
   [junit4]   2> 1826994 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1826995 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1826995 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1826995 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1826995 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1826996 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1826996 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1826996 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1826997 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1826997 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1827006 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1827006 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1827012 T5390 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1
   [junit4]   2> 1827012 T5390 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\'
   [junit4]   2> 1827046 T5390 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1827069 T5390 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1827070 T5390 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1827072 T5390 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1827079 T5390 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1827079 T5390 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1827079 T5390 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\
   [junit4]   2> 1827080 T5390 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1827080 T5390 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\
   [junit4]   2> 1827080 T5390 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index/
   [junit4]   2> 1827080 T5390 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1827082 T5390 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index
   [junit4]   2> 1827082 T5390 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=96.498046875, floorSegmentMB=1.8369140625, forceMergeDeletesPctAllowed=17.187587423784667, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1827083 T5390 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4938e1da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5886c38d),segFN=segments_1,generation=1}
   [junit4]   2> 1827083 T5390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827084 T5390 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1827084 T5390 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1827084 T5390 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1827084 T5390 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1827085 T5390 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1827085 T5390 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1827085 T5390 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1827091 T5390 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1827092 T5390 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1827092 T5390 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1827092 T5390 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=60.828125, floorSegmentMB=0.8818359375, forceMergeDeletesPctAllowed=7.644821412382213, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6855571471345308
   [junit4]   2> 1827092 T5390 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@4938e1da lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5886c38d),segFN=segments_1,generation=1}
   [junit4]   2> 1827093 T5390 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827093 T5390 oass.SolrIndexSearcher.<init> Opening Searcher@60379410 main
   [junit4]   2> 1827093 T5390 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1827093 T5390 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 1827094 T5390 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1827094 T5391 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@60379410 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1827094 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1827094 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1827096 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 1827115 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1827120 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53636
   [junit4]   2> 1827120 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1827121 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1827121 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave
   [junit4]   2> 1827121 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\'
   [junit4]   2> 1827142 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\solr.xml
   [junit4]   2> 1827153 T5380 oasc.CoreContainer.<init> New CoreContainer 556188641
   [junit4]   2> 1827153 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\]
   [junit4]   2> 1827155 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1827155 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1827155 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1827157 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1827157 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1827159 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1827159 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1827159 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1827159 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1827160 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1827164 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1827164 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1827172 T5402 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1
   [junit4]   2> 1827172 T5402 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\'
   [junit4]   2> 1827204 T5402 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1827213 T5402 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1827214 T5402 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1827216 T5402 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1827219 T5402 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1827221 T5402 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1827221 T5402 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\
   [junit4]   2> 1827222 T5402 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1827222 T5402 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\
   [junit4]   2> 1827222 T5402 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index/
   [junit4]   2> 1827222 T5402 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1827223 T5402 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index
   [junit4]   2> 1827223 T5402 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=96.498046875, floorSegmentMB=1.8369140625, forceMergeDeletesPctAllowed=17.187587423784667, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1827223 T5402 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1737035f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8c03760),segFN=segments_1,generation=1}
   [junit4]   2> 1827223 T5402 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827225 T5402 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1827225 T5402 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1827225 T5402 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1827225 T5402 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1827225 T5402 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1827226 T5402 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1827226 T5402 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1827228 T5402 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1827230 T5402 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1827230 T5402 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1827231 T5402 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=60.828125, floorSegmentMB=0.8818359375, forceMergeDeletesPctAllowed=7.644821412382213, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6855571471345308
   [junit4]   2> 1827231 T5402 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1737035f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8c03760),segFN=segments_1,generation=1}
   [junit4]   2> 1827231 T5402 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827232 T5402 oass.SolrIndexSearcher.<init> Opening Searcher@6e61c794 main
   [junit4]   2> 1827233 T5402 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 1827233 T5402 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1827233 T5403 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e61c794 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1827235 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1827235 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1827235 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 1827257 T5380 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=556188641
   [junit4]   2> 1827259 T5380 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@4fd3bf7
   [junit4]   2> 1827272 T5380 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1827272 T5380 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1827272 T5380 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1827273 T5380 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1827274 T5380 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1827274 T5380 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1827274 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index;done=false>>]
   [junit4]   2> 1827275 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index
   [junit4]   2> 1827275 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\;done=false>>]
   [junit4]   2> 1827275 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\
   [junit4]   2> 1827276 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1827343 T5380 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1751065234
   [junit4]   2> 1827345 T5380 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2204ff95
   [junit4]   2> 1827346 T5380 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1827346 T5380 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1827346 T5380 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1827346 T5380 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1827348 T5380 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1827348 T5380 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1827348 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index;done=false>>]
   [junit4]   2> 1827349 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index
   [junit4]   2> 1827349 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\;done=false>>]
   [junit4]   2> 1827350 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\
   [junit4]   2> 1827351 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1827405 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1827409 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53636
   [junit4]   2> 1827412 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1827412 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1827412 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave
   [junit4]   2> 1827412 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\'
   [junit4]   2> 1827431 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\solr.xml
   [junit4]   2> 1827460 T5380 oasc.CoreContainer.<init> New CoreContainer 1959410879
   [junit4]   2> 1827460 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\]
   [junit4]   2> 1827461 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1827461 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1827463 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1827463 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1827463 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1827463 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1827464 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1827464 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1827464 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1827465 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1827470 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1827470 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1827473 T5415 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1
   [junit4]   2> 1827473 T5415 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\'
   [junit4]   2> 1827496 T5415 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1827504 T5415 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1827504 T5415 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1827508 T5415 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1827511 T5415 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1827512 T5415 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
   [junit4]   2> 1827514 T5415 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\
   [junit4]   2> 1827514 T5415 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1827514 T5415 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data
   [junit4]   2> 1827515 T5415 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index/
   [junit4]   2> 1827515 T5415 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1827516 T5415 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index
   [junit4]   2> 1827517 T5415 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.24889992401029987]
   [junit4]   2> 1827528 T5415 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a51eceb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2> 1827528 T5415 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827529 T5415 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1827529 T5415 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1827529 T5415 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1827529 T5415 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1827529 T5415 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1827530 T5415 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1827531 T5415 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1827539 T5415 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1827541 T5415 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1827541 T5415 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1827545 T5415 oass.SolrIndexSearcher.<init> Opening Searcher@87f13d0 main
   [junit4]   2> 1827546 T5415 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 1827546 T5415 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1827546 T5416 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@87f13d0 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 1827547 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1827547 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1827548 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1827553 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53633
   [junit4]   2> 1827555 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1827555 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1827556 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master
   [junit4]   2> 1827556 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\'
   [junit4]   2> 1827607 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\solr.xml
   [junit4]   2> 1827626 T5380 oasc.CoreContainer.<init> New CoreContainer 1146610709
   [junit4]   2> 1827626 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\]
   [junit4]   2> 1827627 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1827627 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1827627 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1827628 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1827628 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1827628 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1827629 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1827629 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1827629 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1827631 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1827639 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1827640 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1827643 T5428 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1
   [junit4]   2> 1827644 T5428 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\'
   [junit4]   2> 1827671 T5428 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1827702 T5428 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1827704 T5428 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1827707 T5428 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1827713 T5428 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1827713 T5428 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
   [junit4]   2> 1827715 T5428 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\
   [junit4]   2> 1827715 T5428 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1827715 T5428 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data
   [junit4]   2> 1827716 T5428 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index/
   [junit4]   2> 1827717 T5428 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1827719 T5428 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index
   [junit4]   2> 1827719 T5428 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.24889992401029987]
   [junit4]   2> 1827730 T5428 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4cef7b2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2> 1827730 T5428 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827732 T5428 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1827732 T5428 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1827732 T5428 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1827732 T5428 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1827734 T5428 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1827734 T5428 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1827734 T5428 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1827743 T5428 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1827744 T5428 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1827744 T5428 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1827746 T5428 oass.SolrIndexSearcher.<init> Opening Searcher@4ecf7581 main
   [junit4]   2> 1827746 T5428 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1827748 T5428 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 1827748 T5428 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1827748 T5429 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4ecf7581 main{StandardDirectoryReader(segments_1:1)}
   [junit4]   2> 1827749 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1827749 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C12024 name=collection1 org.apache.solr.core.SolrCore@61c2bb13
   [junit4]   2> 1827757 T5411 C12024 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=45, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.24889992401029987]
   [junit4]   2> 1827760 T5411 C12024 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a51eceb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2> 1827760 T5411 C12024 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1827761 T5411 C12024 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123456]} 0 9
   [junit4]   2> 1827766 T5413 C12024 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1827800 T5413 C12024 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a51eceb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a51eceb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
   [junit4]   2> 1827800 T5413 C12024 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 1827809 T5413 C12024 oass.SolrIndexSearcher.<init> Opening Searcher@3ed98bba main
   [junit4]   2> 1827810 T5413 C12024 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1827810 T5416 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ed98bba main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
   [junit4]   2> 1827811 T5413 C12024 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 45
   [junit4]   2> 1827840 T5380 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1959410879
   [junit4]   2> 1827841 T5380 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@61c2bb13
   [junit4]   2> 1827843 T5380 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1827843 T5380 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1827843 T5380 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1827844 T5380 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1827846 T5380 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1827846 T5380 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1827846 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index;done=false>>]
   [junit4]   2> 1827847 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index
   [junit4]   2> 1827847 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data;done=false>>]
   [junit4]   2> 1827847 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data
   [junit4]   2> 1827849 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1827903 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1827909 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53636
   [junit4]   2> 1827909 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1827921 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1827922 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave
   [junit4]   2> 1827922 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\'
   [junit4]   2> 1827965 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\solr.xml
   [junit4]   2> 1827981 T5380 oasc.CoreContainer.<init> New CoreContainer 261230331
   [junit4]   2> 1827983 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\]
   [junit4]   2> 1827984 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1827984 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1827984 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1827985 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1827985 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1827985 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1827986 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1827986 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1827987 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1827987 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1827995 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1827997 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1828002 T5440 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1
   [junit4]   2> 1828002 T5440 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\'
   [junit4]   2> 1828031 T5440 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1828038 T5440 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1828039 T5440 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1828043 T5440 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1828049 T5440 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1828050 T5440 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
   [junit4]   2> 1828050 T5440 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\
   [junit4]   2> 1828050 T5440 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1828052 T5440 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data
   [junit4]   2> 1828052 T5440 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index/
   [junit4]   2> 1828053 T5440 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1828053 T5440 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1828054 T5440 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1828054 T5440 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1828054 T5440 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1828054 T5440 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1828054 T5440 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1828061 T5440 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1828063 T5440 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1828063 T5440 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1828064 T5440 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index
   [junit4]   2> 1828067 T5440 oass.SolrIndexSearcher.<init> Opening Searcher@760a2fcf main
   [junit4]   2> 1828068 T5440 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 1828068 T5440 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1828068 T5441 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@760a2fcf main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
   [junit4]   2> 1828070 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1828070 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1828090 T5444 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
   [junit4]   2> 1828090 T5444 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
   [junit4]   2> ASYNC  NEW_CORE C12025 name=collection1 org.apache.solr.core.SolrCore@7ae2ed15
   [junit4]   2> 1828094 T5423 C12025 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C12026 name=collection1 org.apache.solr.core.SolrCore@60e89f90
   [junit4]   2> 1828096 T5435 C12026 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:53633/solr/replication&command=fetchindex&wait=true} status=0 QTime=22 
   [junit4]   2> 1828104 T5380 oas.SolrTestCaseJ4.tearDown ###Ending testNoWriter
   [junit4]   2> 1828119 T5380 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1146610709
   [junit4]   2> 1828122 T5380 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7ae2ed15
   [junit4]   2> 1828122 T5380 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1828122 T5380 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1828123 T5380 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1828123 T5380 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1828124 T5380 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1828125 T5380 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1828125 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index;done=false>>]
   [junit4]   2> 1828125 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data\index
   [junit4]   2> 1828126 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data;done=false>>]
   [junit4]   2> 1828126 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307710\master\collection1\data
   [junit4]   2> 1828126 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1828196 T5380 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=261230331
   [junit4]   2> 1828198 T5380 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@60e89f90
   [junit4]   2> 1829335 T5442 oash.SnapPuller.fetchLatestIndex ERROR Master at: http://127.0.0.1:53633/solr is not available. Index fetch failed. Exception: org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:53633/solr
   [junit4]   2> 1829339 T5380 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1829339 T5380 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1829340 T5380 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1829340 T5380 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1829341 T5380 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1829342 T5380 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1829342 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index;done=false>>]
   [junit4]   2> 1829343 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data\index
   [junit4]   2> 1829343 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data;done=false>>]
   [junit4]   2> 1829345 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056307958\slave\collection1\data
   [junit4]   2> 1829345 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1829473 T5380 oas.SolrTestCaseJ4.setUp ###Starting doTestIndexAndConfigAliasReplication
   [junit4]   2> 1829501 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1829507 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53649
   [junit4]   2> 1829508 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1829508 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1829508 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master
   [junit4]   2> 1829509 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\'
   [junit4]   2> 1829547 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\solr.xml
   [junit4]   2> 1829574 T5380 oasc.CoreContainer.<init> New CoreContainer 1529199906
   [junit4]   2> 1829575 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\]
   [junit4]   2> 1829576 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1829576 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1829576 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1829577 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1829577 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1829579 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1829579 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1829579 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1829580 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1829580 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1829589 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1829590 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1829594 T5454 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1
   [junit4]   2> 1829594 T5454 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\'
   [junit4]   2> 1829619 T5454 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1829627 T5454 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1829629 T5454 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1829633 T5454 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1829640 T5454 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1829640 T5454 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1829641 T5454 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\data\
   [junit4]   2> 1829641 T5454 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1829642 T5454 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\data\
   [junit4]   2> 1829642 T5454 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\data\index/
   [junit4]   2> 1829642 T5454 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1829642 T5454 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310331\master\collection1\data\index
   [junit4]   2> 1829643 T5454 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=96.498046875, floorSegmentMB=1.8369140625, forceMergeDeletesPctAllowed=17.187587423784667, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1829643 T5454 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2501282f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43ae5f4b),segFN=segments_1,generation=1}
   [junit4]   2> 1829643 T5454 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1829645 T5454 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1829645 T5454 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1829646 T5454 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1829646 T5454 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1829646 T5454 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1829646 T5454 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1829646 T5454 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1829651 T5454 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1829654 T5454 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1829654 T5454 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1829655 T5454 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=60.828125, floorSegmentMB=0.8818359375, forceMergeDeletesPctAllowed=7.644821412382213, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6855571471345308
   [junit4]   2> 1829655 T5454 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2501282f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43ae5f4b),segFN=segments_1,generation=1}
   [junit4]   2> 1829655 T5454 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1829656 T5454 oass.SolrIndexSearcher.<init> Opening Searcher@162b3096 main
   [junit4]   2> 1829656 T5454 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1829656 T5454 oash.ReplicationHandler.inform Commits will be reserved for  10000
   [junit4]   2> 1829656 T5454 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1829656 T5455 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@162b3096 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1829657 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1829657 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1829657 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> 1829678 T5380 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1829686 T5380 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53652
   [junit4]   2> 1829687 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 1829691 T5380 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1829691 T5380 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave
   [junit4]   2> 1829692 T5380 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\'
   [junit4]   2> 1829711 T5380 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\solr.xml
   [junit4]   2> 1829720 T5380 oasc.CoreContainer.<init> New CoreContainer 1621387344
   [junit4]   2> 1829721 T5380 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\]
   [junit4]   2> 1829721 T5380 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
   [junit4]   2> 1829721 T5380 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1829722 T5380 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 1829722 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1829722 T5380 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1829722 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1829723 T5380 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1829723 T5380 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1829723 T5380 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1829724 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
   [junit4]   2> 1829729 T5380 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1829729 T5380 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1829733 T5466 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1
   [junit4]   2> 1829733 T5466 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\'
   [junit4]   2> 1829764 T5466 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1829783 T5466 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 1829783 T5466 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 1829787 T5466 oass.IndexSchema.readSchema [null] Schema name=test
   [junit4]   2> 1829792 T5466 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1829792 T5466 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1829792 T5466 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\data\
   [junit4]   2> 1829792 T5466 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1829793 T5466 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\data\
   [junit4]   2> 1829793 T5466 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\data\index/
   [junit4]   2> 1829793 T5466 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 1829794 T5466 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056310521\slave\collection1\data\index
   [junit4]   2> 1829794 T5466 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=96.498046875, floorSegmentMB=1.8369140625, forceMergeDeletesPctAllowed=17.187587423784667, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 1829795 T5466 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@28b9b705 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52025d61),segFN=segments_1,generation=1}
   [junit4]   2> 1829795 T5466 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1829796 T5466 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1829797 T5466 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1829797 T5466 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
   [junit4]   2> 1829797 T5466 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1829797 T5466 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
   [junit4]   2> 1829797 T5466 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1829797 T5466 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 1829800 T5466 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1829802 T5466 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1829802 T5466 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1829802 T5466 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=20, maxMergedSegmentMB=60.828125, floorSegmentMB=0.8818359375, forceMergeDeletesPctAllowed=7.644821412382213, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6855571471345308
   [junit4]   2> 1829803 T5466 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@28b9b705 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52025d61),segFN=segments_1,generation=1}
   [junit4]   2> 1829803 T5466 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1829803 T5466 oass.SolrIndexSearcher.<init> Opening Searcher@78f0ed16 main
   [junit4]   2> 1829804 T5466 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
   [junit4]   2> 1829804 T5466 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1829804 T5467 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78f0ed16 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1829805 T5380 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 1829805 T5380 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 1829805 T5380 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
   [junit4]   2> ASYNC  NEW_CORE C12027 name=collection1 org.apache.solr.core.SolrCore@13deafb4
   [junit4]   2> 1829815 T5448 C12027 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1 
   [junit4]   2> 1829818 T5449 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 0
   [junit4]   2> 1829822 T5450 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 0
   [junit4]   2> 1829826 T5451 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 0
   [junit4]   2> 1829830 T5452 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 0
   [junit4]   2> 1829833 T5453 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 0
   [junit4]   2> 1829837 T5448 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 0
   [junit4]   2> 1829841 T5449 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 0
   [junit4]   2> 1829844 T5450 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
   [junit4]   2> 1829846 T5451 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 0
   [junit4]   2> 1829849 T5452 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 0
   [junit4]   2> 1829852 T5453 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 0
   [junit4]   2> 1829854 T5448 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
   [junit4]   2> 1829858 T5449 C12027 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 0
   [junit4]   2> 1829860 T5450 C12027 oasup.LogUpdateProcessor.finish [coll

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

master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418596\master\collection1\data\;done=false>>]
   [junit4]   2> 1939569 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418596\master\collection1\data\
   [junit4]   2> 1939569 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418596\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418596\master\collection1\data\index;done=false>>]
   [junit4]   2> 1939570 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418596\master\collection1\data\index
   [junit4]   2> 1939574 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1939647 T5380 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=564729873
   [junit4]   2> 1939651 T5380 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@745864dd
   [junit4]   2> 1939653 T5380 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1939655 T5380 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 1939657 T5380 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1939658 T5380 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1939659 T5380 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 1939661 T5380 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1939662 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418810\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418810\slave\collection1\data\;done=false>>]
   [junit4]   2> 1939662 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418810\slave\collection1\data\
   [junit4]   2> 1939665 T5380 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418810\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418810\slave\collection1\data\index;done=false>>]
   [junit4]   2> 1939666 T5380 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1378056418810\slave\collection1\data\index
   [junit4]   2> 1939667 T5380 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1939779 T5380 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Lucene45: {newname=PostingsFormat(name=MockSep), id=PostingsFormat(name=MockSep), name=PostingsFormat(name=MockSep)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=ga_IE, timezone=Asia/Macao
   [junit4]   2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=214160232,total=472825856
   [junit4]   2> NOTE: All tests run in this JVM: [TestSearchPerf, TestCopyFieldCollectionResource, BadIndexSchemaTest, StandardRequestHandlerTest, BadComponentTest, ExternalFileFieldSortTest, DirectSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, LukeRequestHandlerTest, CSVRequestHandlerTest, LeaderElectionTest, RegexBoostProcessorTest, OpenCloseCoreStressTest, TermsComponentTest, TestSolrQueryParserDefaultOperatorResource, TimeZoneUtilsTest, CollectionsAPIDistributedZkTest, TestRangeQuery, TestAddFieldRealTimeGet, TestSolrXmlPersistence, TestExtendedDismaxParser, SolrInfoMBeanTest, TestRecovery, SolrIndexConfigTest, SimplePostToolTest, AddBlockUpdateTest, StatsComponentTest, CoreContainerCoreInitFailuresTest, ScriptEngineTest, TestFastWriter, TestAtomicUpdateErrorCases, DistributedQueryElevationComponentTest, TestUniqueKeyFieldResource, TestPerFieldSimilarity, TestLRUCache, TestClassNameShortening, StatelessScriptUpdateProcessorFactoryTest, TestNoOpRegenerator, UUIDFieldTest, ShowFileRequestHandlerTest, TestFieldCollectionResource, TestFieldResource, TestSerializedLuceneMatchVersion, TestFastLRUCache, ZkCLITest, HdfsDirectoryTest, SolrIndexSplitterTest, DistributedTermsComponentTest, TestBadConfig, SolrCoreTest, SpellCheckCollatorTest, TestQuerySenderListener, TestInfoStreamLogging, TestManagedSchema, FieldMutatingUpdateProcessorTest, TestRemoteStreaming, TestSurroundQueryParser, FullSolrCloudDistribCmdsTest, TestFunctionQuery, DirectUpdateHandlerTest, TestFiltering, UniqFieldsUpdateProcessorFactoryTest, TestBinaryResponseWriter, AssignTest, AliasIntegrationTest, SpellingQueryConverterTest, TestMaxScoreQueryParser, TestQueryTypes, TestRandomMergePolicy, EchoParamsTest, TestComponentsName, ConvertedLegacyTest, ZkNodePropsTest, TestFieldTypeCollectionResource, MinimalSchemaTest, TestDistributedSearch, SolrCmdDistributorTest, TestCollationField, TestDocumentBuilder, PrimUtilsTest, HdfsSyncSliceTest, TestPartialUpdateDeduplication, CurrencyFieldXmlFileTest, JsonLoaderTest, OverseerTest, TestArbitraryIndexDir, TestJmxIntegration, CoreMergeIndexesAdminHandlerTest, TestRandomDVFaceting, TestRecoveryHdfs, TestTrie, LeaderElectionIntegrationTest, FieldAnalysisRequestHandlerTest, WordBreakSolrSpellCheckerTest, CacheHeaderTest, TestCSVResponseWriter, DateFieldTest, TestMergePolicyConfig, TestPHPSerializedResponseWriter, DocValuesMultiTest, TestStressVersions, ResourceLoaderTest, SolrRequestParserTest, TestNRTOpen, ChaosMonkeyNothingIsSafeTest, FileUtilsTest, AddSchemaFieldsUpdateProcessorFactoryTest, SignatureUpdateProcessorFactoryTest, OverseerCollectionProcessorTest, DisMaxRequestHandlerTest, DistributedSpellCheckComponentTest, TestRTGBase, TestLazyCores, TestSolrQueryParser, DirectUpdateHandlerOptimizeTest, TestBM25SimilarityFactory, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestSolrXmlPersistor, DateMathParserTest, IndexSchemaRuntimeFieldTest, TestZkChroot, BlockCacheTest, SystemInfoHandlerTest, TestFuzzyAnalyzedSuggestions, CopyFieldTest, SpellCheckComponentTest, BasicDistributedZkTest, JSONWriterTest, TestCloudManagedSchemaAddField, TestMultiCoreConfBootstrap, DeleteShardTest, TestCloudManagedSchema, ZkSolrClientTest, TestLMDirichletSimilarityFactory, TestSolrDeletionPolicy1, TestSolrIndexConfig, PreAnalyzedUpdateProcessorTest, TestLFUCache, QueryResultKeyTest, RequestHandlersTest, HardAutoCommitTest, PathHierarchyTokenizerFactoryTest, SimpleFacetsTest, TestConfig, TestSuggestSpellingConverter, SuggesterFSTTest, BasicFunctionalityTest, QueryParsingTest, TestPseudoReturnFields, TestFastOutputStream, PeerSyncTest, TestSchemaResource, TestRandomFaceting, TestStressLucene, BinaryUpdateRequestHandlerTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler]
   [junit4] Completed in 112.97s, 14 tests, 2 failures <<< FAILURES!

[...truncated 482 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:396: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:376: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:455: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 324 suites, 1410 tests, 2 failures, 44 ignored (11 assumptions)

Total time: 63 minutes 47 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b102 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message