lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-4.x-Java6 - Build # 587 - Failure
Date Wed, 05 Sep 2012 12:15:31 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-Java6/587/

3 tests failed.
REGRESSION:  org.apache.solr.cloud.RecoveryZkTest.testDistribSearch

Error Message:
Server at http://localhost:42243/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select

Stack Trace:
org.apache.solr.common.SolrException: Server at http://localhost:42243/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
	at __randomizedtesting.SeedInfo.seed([67BCDAD12F37D995:E65A54C95868B9A9]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
	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:616)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
	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:679)


FAILED:  junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler

Error Message:
ERROR: SolrIndexSearcher opens=78 closes=77

Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=78 closes=77
	at __randomizedtesting.SeedInfo.seed([67BCDAD12F37D995]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:223)
	at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:89)
	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:616)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
	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:45)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
	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:679)


REGRESSION:  org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds

Error Message:
Tracker reports too many hard commits expected:<1> but was:<2>

Stack Trace:
java.lang.AssertionError: Tracker reports too many hard commits expected:<1> but was:<2>
	at __randomizedtesting.SeedInfo.seed([67BCDAD12F37D995:366823519E44E932]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:140)
	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:616)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
	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:679)




Build Log:
[...truncated 7518 lines...]
[junit4:junit4] Suite: org.apache.solr.update.SoftAutoCommitTest
[junit4:junit4]   2> -13 T10 oasu.AbstractSolrTestCase.setUp ####SETUP_START testSoftAndHardCommitMaxTimeMixedAdds
[junit4:junit4]   2> 98 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 106 T10 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 107 T10 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1088 T10 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1329 T10 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1379 T10 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1439 T10 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3537 T10 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3571 T10 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3575 T10 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4048 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4067 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4073 T10 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4095 T10 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4159 T10 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4160 T10 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 4161 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 4197 T10 oasc.CoreContainer.<init> New CoreContainer 1453807415
[junit4:junit4]   2> 4318 T10 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846396971/
[junit4:junit4]   2> 4324 T10 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@774b6b02
[junit4:junit4]   2> 4571 T10 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846396971/index/
[junit4:junit4]   2> 4572 T10 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846396971/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4698 T10 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846396971/index forceNew:false
[junit4:junit4]   2> 5142 T10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@475a07bf lockFactory=org.apache.lucene.store.NativeFSLockFactory@6e490342),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5143 T10 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 5190 T10 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6595 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6596 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6596 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6609 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6756 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6771 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6778 T10 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6812 T10 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6829 T10 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6877 T10 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6878 T10 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6883 T10 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6884 T10 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6884 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6885 T10 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6912 T10 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6913 T10 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6914 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6915 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6916 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6917 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6918 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6919 T10 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6942 T10 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6967 T10 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7109 T10 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7146 T10 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7176 T10 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-REQUIRED-FIELDS.XML, SCHEMA-MINIMAL.XML, SCHEMA-REPLICATION2.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SOLRCONFIG-CACHING.XML, SOLRCONFIG-REPEATER.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, SOLRCONFIG-MERGEPOLICY.XML, CURRENCY.XML, SOLRCONFIG-TLOG.XML, SOLRCONFIG-MASTER.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA11.XML, SOLRCONFIG-BASIC.XML, DA_COMPOUNDDICTIONARY.TXT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-SLAVE.XML, ELEVATE.XML, SOLRCONFIG-PROPINJECT-INDEXDEFAULT.XML, TRIVIAL.UPDATEPROCESSOR0.JS, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-IB.XML, SCHEMA-CHARFILTERS.XML, SOLRCONFIG-QUERYSENDER.XML, SCHEMA-REPLICATION1.XML, DA_UTF8.XML, CONDITIONAL.UPDATEPROCESSOR.JS, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, HYPHENATION.DTD, SOLRCONFIG-ENABLEPLUGIN.XML, STEMDICT.TXT, SCHEMA-PHRASESUGGEST.XML, HUNSPELL-TEST.AFF, STOPTYPES-1.TXT, STOPWORDSWRONGENCODING.TXT, SCHEMA-NUMERIC.XML, SOLRCONFIG-TRANSFORMERS.XML, SOLRCONFIG-PROPINJECT.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SOLRCONFIG-SIMPLELOCK.XML, WDFTYPES.TXT, STOPTYPES-2.TXT, SCHEMA-REVERSED.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, SOLRCONFIG-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, KEEP-1.TXT, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SOLRCONFIG-SPELLCHECKER.XML, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SOLRCONFIG-MASTER1.XML, SYNONYMS.TXT, SCHEMA.XML, SCHEMA_CODEC.XML, SOLRCONFIG-SOLR-749.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, STOP-2.TXT, SOLRCONFIG-FUNCTIONQUERY.XML, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-TERMINDEX.XML, SOLRCONFIG-ELEVATE.XML, STOPWORDS.TXT, SCHEMA-FOLDING.XML, SCHEMA-STOP-KEEP.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, STOP-1.TXT, SOLRCONFIG-MASTER2.XML, SCHEMA-SPELLCHECKER.XML, SOLRCONFIG-LAZYWRITER.XML, SCHEMA-LUCENEMATCHVERSION.XML, BAD-MP-SOLRCONFIG.XML, FRENCHARTICLES.TXT, SCHEMA15.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMASURROUND.XML, SCHEMA-COLLATEFILTER.XML, SOLRCONFIG-MASTER3.XML, HUNSPELL-TEST.DIC, SOLRCONFIG-XINCLUDE.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-DELPOLICY1.XML, SOLRCONFIG-SLAVE1.XML, SCHEMA-SIM.XML, SCHEMA-COLLATE.XML, STOP-SNOWBALL.TXT, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, PROTWORDS.TXT, SCHEMA-TRIE.XML, SOLRCONFIG_CODEC.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SCHEMA-TFIDF.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SCHEMA-LMJELINEKMERCER.XML, PHRASESUGGEST.TXT, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, SOLRCONFIG-BASIC-LUCENEVERSION31.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-DELPOLICY2.XML, XSLT, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-BM25.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SOLRCONFIG-ALTDIRECTORY.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG_PERF.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, KEEP-2.TXT, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA12.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD_SOLRCONFIG.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML]
[junit4:junit4]   2> 7245 T10 oass.SolrIndexSearcher.<init> Opening Searcher@4c4ab84c main
[junit4:junit4]   2> 7246 T10 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 7336 T10 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7337 T10 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7338 T10 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7541 T10 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7940 T10 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 7941 T10 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 7941 T10 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 7942 T10 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 7943 T10 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 7943 T10 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 7944 T10 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 7944 T10 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 7945 T10 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 7994 T10 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8471 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c4ab84c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8480 T10 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8484 T10 oasu.AbstractSolrTestCase.setUp ####SETUP_END testSoftAndHardCommitMaxTimeMixedAdds
[junit4:junit4]   2> ASYNC  NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@7f1e1bbf
[junit4:junit4]   2> 8562 T10 C0 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 8835 T10 C0 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@475a07bf lockFactory=org.apache.lucene.store.NativeFSLockFactory@6e490342),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8836 T10 C0 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9131 T10 C0 UPDATE [collection1] webapp=null path=null params={} {add=[529]} 0 594
[junit4:junit4]   2> 9633 T13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 10324 T12 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10341 T13 oass.SolrIndexSearcher.<init> Opening Searcher@11eea7f0 main
[junit4:junit4]   2> 10346 T13 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10353 T13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10361 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11eea7f0 main{StandardDirectoryReader(segments_1:3:nrt _0(4.0.0.2):C1)}
[junit4:junit4]   2> 10365 T12 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@475a07bf lockFactory=org.apache.lucene.store.NativeFSLockFactory@6e490342),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@475a07bf lockFactory=org.apache.lucene.store.NativeFSLockFactory@6e490342),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0.fnm, _0_Memory_0.ram, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Lucene40_0.tip, _0_Lucene40WithOrds_0.tii, _0_Lucene40_0.frq, _0_Lucene40WithOrds_0.frq, _0_nrm.cfe, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0.si, _0_Lucene40WithOrds_0.tib, _0.fdt]
[junit4:junit4]   2> 10366 T12 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 10367 T12 oass.SolrIndexSearcher.<init> Opening Searcher@4eb64f2e main
[junit4:junit4]   2> 10367 T12 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10368 T12 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10371 T10 C0 UPDATE [collection1] webapp=null path=null params={} {add=[530]} 0 3
[junit4:junit4]   2> 10373 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4eb64f2e main{StandardDirectoryReader(segments_1:3:nrt _0(4.0.0.2):C1)}
[junit4:junit4]   2> 10872 T13 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 10943 T13 oass.SolrIndexSearcher.<init> Opening Searcher@2140243b main
[junit4:junit4]   2> 10943 T13 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 10944 T13 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10948 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2140243b main{StandardDirectoryReader(segments_2:5:nrt _0(4.0.0.2):C1 _1(4.0.0.2):C1)}
[junit4:junit4]   2> 11573 T12 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11575 T10 oasu.AbstractSolrTestCase.tearDown ####TEARDOWN_START testSoftAndHardCommitMaxTimeMixedAdds
[junit4:junit4]   2> 11575 T12 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@475a07bf lockFactory=org.apache.lucene.store.NativeFSLockFactory@6e490342),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0.fnm, _0_Memory_0.ram, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Lucene40_0.tip, _0_Lucene40WithOrds_0.tii, _0_Lucene40_0.frq, _0_Lucene40WithOrds_0.frq, _0_nrm.cfe, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0.si, _0_Lucene40WithOrds_0.tib, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@475a07bf lockFactory=org.apache.lucene.store.NativeFSLockFactory@6e490342),segFN=segments_3,generation=3,filenames=[_1_Lucene40_0.tim, _1_Lucene40WithOrds_0.prx, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.blm, _0_Lucene40_0.prx, _1.fnm, _1_Lucene40_0.tip, _1_TestBloomFilteredLucene40Postings_0.tip, _1_Memory_0.ram, _0_Lucene40WithOrds_0.tii, _0_Lucene40WithOrds_0.frq, _0_Lucene40_0.frq, _1_Lucene40WithOrds_0.tib, _0_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40WithOrds_0.tii, _1_TestBloomFilteredLucene40Postings_0.tim, _0.si, _0_Lucene40WithOrds_0.tib, _0_TestBloomFilteredLucene40Postings_0.tim, _1_nrm.cfs, _1_Lucene40WithOrds_0.frq, _0_Lucene40_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _1_TestBloomFilteredLucene40Postings_0.prx, _0_Memory_0.ram, _1_Lucene40_0.frq, _0_nrm.cfs, _1_TestBloomFilteredLucene40Postings_0.frq, _1_Lucene40_0.prx, _1_nrm.cfe, _0_Lucene40_0.tip, _1.fdx, _1.fdt, _1.si, _0_nrm.cfe, _0.fdx, _0_Lucene40WithOrds_0.prx, segments_3, _0.fdt]
[junit4:junit4]   2> 11576 T10 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1453807415
[junit4:junit4]   2> 11576 T12 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 11578 T12 oass.SolrIndexSearcher.<init> Opening Searcher@10439d3b main
[junit4:junit4]   2> 11578 T12 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 11579 T12 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11580 T10 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7f1e1bbf
[junit4:junit4]   2> 11587 T10 oasc.SolrException.log SEVERE org.apache.solr.common.SolrException: Failed to unregister info bean: queryResultCache
[junit4:junit4]   2> 		at org.apache.solr.core.JmxMonitoredMap.unregister(JmxMonitoredMap.java:178)
[junit4:junit4]   2> 		at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
[junit4:junit4]   2> 		at org.apache.solr.core.SolrCore.close(SolrCore.java:864)
[junit4:junit4]   2> 		at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:623)
[junit4:junit4]   2> 		at org.apache.solr.util.TestHarness.close(TestHarness.java:434)
[junit4:junit4]   2> 		at org.apache.solr.util.AbstractSolrTestCase.tearDown(AbstractSolrTestCase.java:209)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:616)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:795)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]   2> 		at java.lang.Thread.run(Thread.java:679)
[junit4:junit4]   2> 	Caused by: javax.management.InstanceNotFoundException: solr/collection1:type=queryResultCache,id=org.apache.solr.search.LRUCache
[junit4:junit4]   2> 		at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1118)
[junit4:junit4]   2> 		at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:433)
[junit4:junit4]   2> 		at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421)
[junit4:junit4]   2> 		at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:540)
[junit4:junit4]   2> 		at org.apache.solr.core.JmxMonitoredMap.unregister(JmxMonitoredMap.java:175)
[junit4:junit4]   2> 		... 42 more
[junit4:junit4]   2> 	
[junit4:junit4]   2> 11588 T11 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10439d3b main{StandardDirectoryReader(segments_2:5:nrt _0(4.0.0.2):C1 _1(4.0.0.2):C1)}
[junit4:junit4]   2> 11590 T10 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 11592 T10 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommit maxTime=1200ms,autocommits=2,soft autocommit maxTime=500ms,soft autocommits=2,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 11592 T10 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 11755 T10 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SoftAutoCommitTest -Dtests.method=testSoftAndHardCommitMaxTimeMixedAdds -Dtests.seed=67BCDAD12F37D995 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mk_MK -Dtests.timezone=Africa/Mbabane -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] FAILURE 11.9s J1 | SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: Tracker reports too many hard commits expected:<1> but was:<2>
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([67BCDAD12F37D995:366823519E44E932]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.junit.Assert.failNotEquals(Assert.java:647)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:128)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:472)
[junit4:junit4]    > 	at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:140)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:616)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:679)
[junit4:junit4]   2> 11871 T10 oasu.AbstractSolrTestCase.setUp ####SETUP_START testSoftAndHardCommitMaxTimeDelete
[junit4:junit4]   2> 11873 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 11874 T10 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 11875 T10 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 12012 T10 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 12087 T10 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 12088 T10 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 12125 T10 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 12795 T10 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 12808 T10 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 12812 T10 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 12830 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12835 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 12839 T10 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12842 T10 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 12846 T10 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 12846 T10 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 12847 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 12856 T10 oasc.CoreContainer.<init> New CoreContainer 1170329537
[junit4:junit4]   2> 12856 T10 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846408835/
[junit4:junit4]   2> 12857 T10 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@774b6b02
[junit4:junit4]   2> 12858 T10 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846408835/index/
[junit4:junit4]   2> 12859 T10 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846408835/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 12880 T10 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846408835/index forceNew:false
[junit4:junit4]   2> 12883 T10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/index5777957779tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@da8e53c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12884 T10 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 12884 T10 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 12887 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 12888 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 12888 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 12889 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 12890 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 12890 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 12891 T10 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 12892 T10 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 12893 T10 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 12894 T10 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 12895 T10 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 12896 T10 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 12897 T10 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 12897 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 12898 T10 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 12899 T10 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 12900 T10 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12901 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12902 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12904 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12905 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12906 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12907 T10 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 12909 T10 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 12910 T10 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 12913 T10 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 12917 T10 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 12920 T10 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-REQUIRED-FIELDS.XML, SCHEMA-MINIMAL.XML, SCHEMA-REPLICATION2.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SOLRCONFIG-CACHING.XML, SOLRCONFIG-REPEATER.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, SOLRCONFIG-MERGEPOLICY.XML, CURRENCY.XML, SOLRCONFIG-TLOG.XML, SOLRCONFIG-MASTER.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA11.XML, SOLRCONFIG-BASIC.XML, DA_COMPOUNDDICTIONARY.TXT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-SLAVE.XML, ELEVATE.XML, SOLRCONFIG-PROPINJECT-INDEXDEFAULT.XML, TRIVIAL.UPDATEPROCESSOR0.JS, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-IB.XML, SCHEMA-CHARFILTERS.XML, SOLRCONFIG-QUERYSENDER.XML, SCHEMA-REPLICATION1.XML, DA_UTF8.XML, CONDITIONAL.UPDATEPROCESSOR.JS, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, HYPHENATION.DTD, SOLRCONFIG-ENABLEPLUGIN.XML, STEMDICT.TXT, SCHEMA-PHRASESUGGEST.XML, HUNSPELL-TEST.AFF, STOPTYPES-1.TXT, STOPWORDSWRONGENCODING.TXT, SCHEMA-NUMERIC.XML, SOLRCONFIG-TRANSFORMERS.XML, SOLRCONFIG-PROPINJECT.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SOLRCONFIG-SIMPLELOCK.XML, WDFTYPES.TXT, STOPTYPES-2.TXT, SCHEMA-REVERSED.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, SOLRCONFIG-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, KEEP-1.TXT, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SOLRCONFIG-SPELLCHECKER.XML, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SOLRCONFIG-MASTER1.XML, SYNONYMS.TXT, SCHEMA.XML, SCHEMA_CODEC.XML, SOLRCONFIG-SOLR-749.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, STOP-2.TXT, SOLRCONFIG-FUNCTIONQUERY.XML, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-TERMINDEX.XML, SOLRCONFIG-ELEVATE.XML, STOPWORDS.TXT, SCHEMA-FOLDING.XML, SCHEMA-STOP-KEEP.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, STOP-1.TXT, SOLRCONFIG-MASTER2.XML, SCHEMA-SPELLCHECKER.XML, SOLRCONFIG-LAZYWRITER.XML, SCHEMA-LUCENEMATCHVERSION.XML, BAD-MP-SOLRCONFIG.XML, FRENCHARTICLES.TXT, SCHEMA15.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMASURROUND.XML, SCHEMA-COLLATEFILTER.XML, SOLRCONFIG-MASTER3.XML, HUNSPELL-TEST.DIC, SOLRCONFIG-XINCLUDE.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-DELPOLICY1.XML, SOLRCONFIG-SLAVE1.XML, SCHEMA-SIM.XML, SCHEMA-COLLATE.XML, STOP-SNOWBALL.TXT, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, PROTWORDS.TXT, SCHEMA-TRIE.XML, SOLRCONFIG_CODEC.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SCHEMA-TFIDF.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SCHEMA-LMJELINEKMERCER.XML, PHRASESUGGEST.TXT, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, SOLRCONFIG-BASIC-LUCENEVERSION31.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-DELPOLICY2.XML, XSLT, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-BM25.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SOLRCONFIG-ALTDIRECTORY.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG_PERF.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, KEEP-2.TXT, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA12.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD_SOLRCONFIG.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML]
[junit4:junit4]   2> 12940 T10 oass.SolrIndexSearcher.<init> Opening Searcher@35703484 main
[junit4:junit4]   2> 12941 T10 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 12942 T10 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 12943 T10 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 12945 T10 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 12955 T10 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 12997 T10 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 12998 T10 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 12998 T10 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 12999 T10 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 12999 T10 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 13000 T10 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 13000 T10 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 13000 T10 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 13001 T10 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 13001 T10 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 13016 T14 oasc.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo WARNING Could not getStatistics on info bean org.apache.solr.search.SolrIndexSearcher org.apache.lucene.store.AlreadyClosedException: this IndexReader is closed
[junit4:junit4]   2> 	at org.apache.lucene.index.IndexReader.ensureOpen(IndexReader.java:252)
[junit4:junit4]   2> 	at org.apache.lucene.index.StandardDirectoryReader.getVersion(StandardDirectoryReader.java:307)
[junit4:junit4]   2> 	at org.apache.solr.search.SolrIndexSearcher.getStatistics(SolrIndexSearcher.java:2007)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:231)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1824)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1661)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1833)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:436)
[junit4:junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:421)
[junit4:junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:540)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:138)
[junit4:junit4]   2> 	at org.apache.solr.core.JmxMonitoredMap.put(JmxMonitoredMap.java:51)
[junit4:junit4]   2> 	at org.apache.solr.search.SolrIndexSearcher.register(SolrIndexSearcher.java:242)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.registerSearcher(SolrCore.java:1634)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore.access$000(SolrCore.java:121)
[junit4:junit4]   2> 	at org.apache.solr.core.SolrCore$7.call(SolrCore.java:1511)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[junit4:junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]   2> 	at java.lang.Thread.run(Thread.java:679)
[junit4:junit4]   2> 
[junit4:junit4]   2> 13023 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@35703484 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13028 T10 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 13031 T10 oasu.AbstractSolrTestCase.setUp ####SETUP_END testSoftAndHardCommitMaxTimeDelete
[junit4:junit4]   2> ASYNC  NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@590948cf
[junit4:junit4]   2> 13042 T10 C1 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/index5777957779tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@da8e53c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13042 T10 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13047 T10 C1 UPDATE [collection1] webapp=null path=null params={} {add=[529]} 0 9
[junit4:junit4]   2> 13059 T10 C1 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13077 T10 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/index5777957779tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@da8e53c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/index5777957779tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@da8e53c),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0.fnm, _0_Memory_0.ram, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Lucene40_0.tip, _0_Lucene40WithOrds_0.tii, _0_Lucene40_0.frq, _0_Lucene40WithOrds_0.frq, _0_nrm.cfe, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0.si, _0_Lucene40WithOrds_0.tib, _0.fdt]
[junit4:junit4]   2> 13077 T10 C1 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 13098 T10 C1 oass.SolrIndexSearcher.<init> Opening Searcher@15837e8 main
[junit4:junit4]   2> 13098 T10 C1 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13099 T10 C1 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13104 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15837e8 main{StandardDirectoryReader(segments_2:3 _0(4.0.0.2):C1)}
[junit4:junit4]   2> 13106 T10 C1 UPDATE [collection1] webapp=null path=null params={} {commit=} 0 47
[junit4:junit4]   2> 13117 T10 C1 UPDATE [collection1] webapp=null path=null params={} {delete=[529]} 0 3
[junit4:junit4]   2> 13619 T16 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 13637 T16 oass.SolrIndexSearcher.<init> Opening Searcher@6b5b5783 main
[junit4:junit4]   2> 13637 T16 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 13638 T16 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13642 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6b5b5783 main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4:junit4]   2> 13649 T10 C1 UPDATE [collection1] webapp=null path=null params={} {add=[550]} 0 5
[junit4:junit4]   2> 14150 T16 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true}
[junit4:junit4]   2> 14170 T16 oass.SolrIndexSearcher.<init> Opening Searcher@43824d2c main
[junit4:junit4]   2> 14170 T16 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14171 T16 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14173 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@43824d2c main{StandardDirectoryReader(segments_2:7:nrt _1(4.0.0.2):C1)}
[junit4:junit4]   2> 14319 T15 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 14322 T15 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/index5777957779tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@da8e53c),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0.fnm, _0_Memory_0.ram, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Lucene40_0.tip, _0_Lucene40WithOrds_0.tii, _0_Lucene40_0.frq, _0_Lucene40WithOrds_0.frq, _0_nrm.cfe, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0.si, _0_Lucene40WithOrds_0.tib, _0.fdt]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.MMapDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/index5777957779tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@da8e53c),segFN=segments_3,generation=3,filenames=[_1_Lucene40_0.tim, _1_nrm.cfs, _1_Lucene40WithOrds_0.frq, _1_TestBloomFilteredLucene40Postings_0.prx, _1_Lucene40WithOrds_0.prx, _1_Lucene40_0.frq, _1_TestBloomFilteredLucene40Postings_0.blm, _1.fnm, _1_TestBloomFilteredLucene40Postings_0.frq, _1_nrm.cfe, _1_Lucene40_0.prx, _1_Lucene40_0.tip, _1.fdx, _1_TestBloomFilteredLucene40Postings_0.tip, _1_Memory_0.ram, _1_Lucene40WithOrds_0.tib, _1.fdt, _1.si, _1_Lucene40WithOrds_0.tii, _1_TestBloomFilteredLucene40Postings_0.tim, segments_3]
[junit4:junit4]   2> 14322 T15 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 14328 T15 oass.SolrIndexSearcher.<init> Opening Searcher@50baa573 main
[junit4:junit4]   2> 14329 T15 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 14329 T15 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 14332 T14 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@50baa573 main{StandardDirectoryReader(segments_2:7:nrt _1(4.0.0.2):C1)}
[junit4:junit4]   2> 16329 T10 oasu.AbstractSolrTestCase.tearDown ####TEARDOWN_START testSoftAndHardCommitMaxTimeDelete
[junit4:junit4]   2> 16329 T10 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1170329537
[junit4:junit4]   2> 16330 T10 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@590948cf
[junit4:junit4]   2> 16348 T10 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 16348 T10 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommit maxTime=1200ms,autocommits=1,soft autocommit maxTime=500ms,soft autocommits=2,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 16349 T10 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 16368 T10 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 16388 T10 oasu.AbstractSolrTestCase.setUp ####SETUP_START testSoftAndHardCommitMaxTimeRapidAdds
[junit4:junit4]   2> 16389 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 16390 T10 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 16391 T10 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 16497 T10 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 16568 T10 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 16569 T10 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 16601 T10 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 17318 T10 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 17331 T10 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 17334 T10 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 17351 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17356 T10 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 17359 T10 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17362 T10 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 17364 T10 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 17364 T10 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 17364 T10 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 17373 T10 oasc.CoreContainer.<init> New CoreContainer 1349374216
[junit4:junit4]   2> 17373 T10 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846413352/
[junit4:junit4]   2> 17374 T10 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@774b6b02
[junit4:junit4]   2> 17375 T10 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846413352/index/
[junit4:junit4]   2> 17375 T10 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846413352/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 17376 T10 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.update.SoftAutoCommitTest-1346846413352/index forceNew:false
[junit4:junit4]   2> 17378 T10 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6001ef4b lockFactory=org.apache.lucene.store.NativeFSLockFactory@175c30f6),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17378 T10 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 17379 T10 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 17381 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 17382 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 17382 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 17383 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 17384 T10 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 17385 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 17385 T10 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 17386 T10 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 17386 T10 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 17387 T10 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 17388 T10 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 17388 T10 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 17389 T10 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 17389 T10 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 17390 T10 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 17391 T10 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 17391 T10 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17392 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17393 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17393 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17394 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17395 T10 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17395 T10 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 17396 T10 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 17396 T10 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 17399 T10 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 17402 T10 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 17404 T10 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-REQUIRED-FIELDS.XML, SCHEMA-MINIMAL.XML, SCHEMA-REPLICATION2.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SOLRCONFIG-CACHING.XML, SOLRCONFIG-REPEATER.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, SOLRCONFIG-MERGEPOLICY.XML, CURRENCY.XML, SOLRCONFIG-TLOG.XML, SOLRCONFIG-MASTER.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA11.XML, SOLRCONFIG-BASIC.XML, DA_COMPOUNDDICTIONARY.TXT, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-SLAVE.XML, ELEVATE.XML, SOLRCONFIG-PROPINJECT-INDEXDEFAULT.XML, TRIVIAL.UPDATEPROCESSOR0.JS, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-IB.XML, SCHEMA-CHARFILTERS.XML, SOLRCONFIG-QUERYSENDER.XML, SCHEMA-REPLICATION1.XML, DA_UTF8.XML, CONDITIONAL.UPDATEPROCESSOR.JS, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, HYPHENATION.DTD, SOLRCONFIG-ENABLEPLUGIN.XML, STEMDICT.TXT, SCHEMA-PHRASESUGGEST.XML, HUNSPELL-TEST.AFF, STOPTYPES-1.TXT, STOPWORDSWRONGENCODING.TXT, SCHEMA-NUMERIC.XML, SOLRCONFIG-TRANSFORMERS.XML, SOLRCONFIG-PROPINJECT.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SOLRCONFIG-SIMPLELOCK.XML, WDFTYPES.TXT, STOPTYPES-2.TXT, SCHEMA-REVERSED.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, SOLRCONFIG-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, KEEP-1.TXT, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SOLRCONFIG-SPELLCHECKER.XML, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SOLRCONFIG-MASTER1.XML, SYNONYMS.TXT, SCHEMA.XML, SCHEMA_CODEC.XML, SOLRCONFIG-SOLR-749.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, STOP-2.TXT, SOLRCONFIG-FUNCTIONQUERY.XML, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-TERMINDEX.XML, SOLRCONFIG-ELEVATE.XML, STOPWORDS.TXT, SCHEMA-FOLDING.XML, SCHEMA-STOP-KEEP.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, STOP-1.TXT, SOLRCONFIG-MASTER2.XML, SCHEMA-SPELLCHECKER.XML, SOLRCONFIG-LAZYWRITER.XML, SCHEMA-LUCENEMATCHVERSION.XML, BAD-MP-SOLRCONFIG.XML, FRENCHARTICLES.TXT, SCHEMA15.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMASURROUND.XML, SCHEMA-COLLATEFILTER.XML, SOLRCONFIG-MASTER3.XML, HUNSPELL-TEST.DIC, SOLRCONFIG-XINCLUDE.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-DELPOLICY1.XML, SOLRCONFIG-SLAVE1.XML, SCHEMA-SIM.XML, SCHEMA-COLLATE.XML, STOP-SNOWBALL.TXT, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, PROTWORDS.TXT, SCHEMA-TRIE.XML, SOLRCONFIG_CODEC.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SCHEMA-TFIDF.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SCHEMA-LMJELINEKMERCER.XML, PHRASESUGGEST.TXT, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, SOLRCONFIG-BASIC-LUCENEVERSION31.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-DELPOLICY2.XML, XSLT, SOLRCONFIG-NATIVELOCK.XML, BAD-SCHEMA-DUP-FIELD.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-BM25.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SOLRCONFIG-ALTDIRECTORY.XML, COMPOUNDDICTIONARY.TXT, SOLRCONFIG_PERF.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, KEEP-2.TXT, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA12.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD_SOLRCONFIG.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML]
[junit4:junit4]   2> 17407 T10 oass.SolrIndexSearcher.<init> Opening Searcher@3a396fce main
[junit4:junit4]   2> 17407 T10 oass.SolrIndexSearcher.getIndexDir WARNING WARNING: Directory impl does not support setting indexDir: org.apache.lucene.store.MockDirectoryWrapper
[junit4:junit4]   2> 17408 T10 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 17408 T10 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 17409 T10 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 17415 T10 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 17457 T10 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 17457 T10 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 17458 T10 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 17458 T10 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 17458 T10 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 17459 T10 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 17459 T10 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 17460 T10 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 17460 T10 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 17460 T10 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 17476 T17 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a396fce main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 17482 T10 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 17485 T10 oasu.AbstractSolrTestCase.setUp ####SETUP_END testSoftAndHardCommitMaxTimeRapidAdds
[junit4:junit4]   2> ASYNC  NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@59b68d78
[junit4:junit4]   2> 17492 T10 C2 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6001ef4b lockFactory=org.apache.lucene.store.NativeFSLockFactory@175c30f6),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 17493 T10 C2 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 17496 T10 C2 UPDATE [collection1] webapp=null path=null params={} {add=[5000]} 0 6
[junit4:junit4]   2> 17509 T10 C2 UPDATE [collection1] webapp=null path=null params={} {add=[5001]} 0 3
[junit4:junit4]   2> 17515 T10 C2 UPDATE [collection1] webapp=null path=null params={} {add=[5002]} 0 1
[junit4:junit4]   2> 17522 T10 C2 UPDATE [collection1] webapp=null path=null params={} {add=[5003]} 0 2
[junit4:junit4]   2> 17529 T10 C2 UPDATE [collection1] webapp=null path=null params={} {add=[5004]} 0 2
[junit4:junit4]   2> 17531 T10 oasu.AbstractSolrTestCase.tearDown ####TEARDOWN_START testSoftAndHardCommitMaxTimeRapidAdds
[junit4:junit4]   2> 17531 T10 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1349374216
[junit4:junit4]   2> 17531 T10 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@59b68d78
[junit4:junit4]   2> 17548 T10 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 17549 T10 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommit maxTime=1200ms,autocommits=0,soft autocommit maxTime=500ms,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=5,adds=5,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 17550 T10 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 17557 T10 C2 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6001ef4b lockFactory=org.apache.lucene.store.NativeFSLockFactory@175c30f6),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6001ef4b lockFactory=org.apache.lucene.store.NativeFSLockFactory@175c30f6),segFN=segments_2,generation=2,filenames=[_0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _0_TestBloomFilteredLucene40Postings_0.tip, _0_Lucene40_0.tim, _0.fnm, _0_Memory_0.ram, _0_TestBloomFilteredLucene40Postings_0.prx, _0_nrm.cfs, _0_Lucene40_0.prx, _0_Lucene40_0.tip, _0_Lucene40WithOrds_0.tii, _0_Lucene40_0.frq, _0_Lucene40WithOrds_0.frq, _0_nrm.cfe, segments_2, _0_TestBloomFilteredLucene40Postings_0.frq, _0.fdx, _0_Lucene40WithOrds_0.prx, _0.si, _0_Lucene40WithOrds_0.tib, _0.fdt]
[junit4:junit4]   2> 17557 T10 C2 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 17564 T10 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {timestamp=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=Lucene40WithOrds), range_facet_sl=PostingsFormat(name=TestBloomFilteredLucene40Postings), text=PostingsFormat(name=TestBloomFilteredLucene40Postings), range_facet_si=Lucene40(minBlockSize=43 maxBlockSize=122), subject=Lucene40(minBlockSize=43 maxBlockSize=122), multiDefault=Lucene40(minBlockSize=43 maxBlockSize=122), intDefault=PostingsFormat(name=Memory doPackFST= true)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=mk_MK, timezone=Africa/Mbabane
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=1,free=99593880,total=142475264
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SoftAutoCommitTest]
[junit4:junit4] Completed on J1 in 19.54s, 3 tests, 1 failure <<< FAILURES!

[...truncated 36 lines...]
[junit4:junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4:junit4]   2> 564 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 921 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26423
[junit4:junit4]   2> 954 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 955 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master
[junit4:junit4]   2> 955 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 993 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 994 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 994 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master
[junit4:junit4]   2> 995 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/solr.xml
[junit4:junit4]   2> 995 T273 oasc.CoreContainer.<init> New CoreContainer 2124724698
[junit4:junit4]   2> 996 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 996 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 996 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 1037 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1091 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1
[junit4:junit4]   2> 1092 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/'
[junit4:junit4]   2> 1132 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1185 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1186 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1190 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1209 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1210 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/
[junit4:junit4]   2> 1211 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 1235 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index/
[junit4:junit4]   2> 1236 T273 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1237 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index forceNew:false
[junit4:junit4]   2> 1242 T273 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1242 T273 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1243 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1277 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1278 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1278 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1279 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1279 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1282 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1288 T273 oass.SolrIndexSearcher.<init> Opening Searcher@38351fbf main
[junit4:junit4]   2> 1289 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1289 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1290 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1290 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1291 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1291 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1291 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1292 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1292 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1293 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1293 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1293 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1314 T273 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 1318 T273 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 1319 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1319 T284 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38351fbf main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1320 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1321 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1394 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1428 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 1430 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26424
[junit4:junit4]   2> 1431 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1431 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave
[junit4:junit4]   2> 1432 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 1471 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 1472 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1473 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave
[junit4:junit4]   2> 1473 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/solr.xml
[junit4:junit4]   2> 1473 T273 oasc.CoreContainer.<init> New CoreContainer 1088575502
[junit4:junit4]   2> 1474 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 1475 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 1475 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 1501 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1521 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1
[junit4:junit4]   2> 1522 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/'
[junit4:junit4]   2> 1564 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1616 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1617 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1621 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1642 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1644 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/
[junit4:junit4]   2> 1644 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 1645 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/
[junit4:junit4]   2> 1646 T273 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1647 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index forceNew:false
[junit4:junit4]   2> 1652 T273 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1652 T273 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 1654 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1654 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1655 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1655 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1655 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1656 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1660 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1664 T273 oass.SolrIndexSearcher.<init> Opening Searcher@4e3a2053 main
[junit4:junit4]   2> 1666 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1666 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1667 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1667 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1668 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1668 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1669 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1669 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1670 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1670 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1670 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1671 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1686 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 1814 T273 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 1815 T294 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e3a2053 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1814 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1815 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 1816 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 1816 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 1833 T273 oas.SolrTestCaseJ4.setUp ###Starting test
[junit4:junit4]   2> 1834 T273 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1088575502
[junit4:junit4]   2> 1835 T273 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@21b7682d
[junit4:junit4]   2> 1836 T273 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1836 T273 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:junit4]   2> 1837 T273 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1837 T273 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 1838 T273 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> ASYNC  NEW_CORE C34 name=collection1 org.apache.solr.core.SolrCore@637c67f9
[junit4:junit4]   2> 2776 T276 C34 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 2781 T276 C34 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2782 T276 C34 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2783 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 37
[junit4:junit4]   2> 2916 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 2
[junit4:junit4]   2> 2920 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4:junit4]   2> 2924 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4:junit4]   2> 2928 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4:junit4]   2> 2931 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4:junit4]   2> 2934 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 1
[junit4:junit4]   2> 2938 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4:junit4]   2> 2940 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4:junit4]   2> 2944 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4:junit4]   2> 2948 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4:junit4]   2> 2982 T276 C34 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 3024 T276 C34 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.tis, _0.nrm, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 3025 T276 C34 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 3031 T276 C34 oass.SolrIndexSearcher.<init> Opening Searcher@49dd63c9 main
[junit4:junit4]   2> 3031 T276 C34 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 3032 T284 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@49dd63c9 main{StandardDirectoryReader(segments_2:4 _0(4.0.0.2):C10)}
[junit4:junit4]   2> 3032 T276 C34 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 50
[junit4:junit4]   2> 3092 T276 C34 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=34 
[junit4:junit4]   2> 3198 T273 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=2124724698
[junit4:junit4]   2> 3198 T273 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@637c67f9
[junit4:junit4]   2> 3199 T273 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 3199 T273 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=10,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4:junit4]   2> 3200 T273 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 3201 T273 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 3202 T273 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 3258 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3261 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26426
[junit4:junit4]   2> 3261 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3262 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master
[junit4:junit4]   2> 3262 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 3294 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3295 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3295 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master
[junit4:junit4]   2> 3295 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/solr.xml
[junit4:junit4]   2> 3296 T273 oasc.CoreContainer.<init> New CoreContainer 1101213924
[junit4:junit4]   2> 3296 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 3296 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 3297 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 3315 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3335 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1
[junit4:junit4]   2> 3336 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/'
[junit4:junit4]   2> 3370 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3425 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3426 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3430 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3453 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3455 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/
[junit4:junit4]   2> 3455 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3456 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index/
[junit4:junit4]   2> 3458 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3459 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3459 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3460 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3460 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3461 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3465 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3468 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index forceNew:false
[junit4:junit4]   2> 3475 T273 oass.SolrIndexSearcher.<init> Opening Searcher@749652e4 main
[junit4:junit4]   2> 3476 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3476 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3477 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3477 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3478 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3478 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3479 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3479 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3480 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3480 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3481 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3481 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3499 T273 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 3500 T273 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 3501 T273 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 3502 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index forceNew:true
[junit4:junit4]   2> 3507 T273 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.nrm, _0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 3507 T273 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 3508 T273 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 3508 T273 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 3509 T305 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@749652e4 main{StandardDirectoryReader(segments_2:4 _0(4.0.0.2):C10)}
[junit4:junit4]   2> 3509 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3510 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3510 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3511 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3527 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3530 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26427
[junit4:junit4]   2> 3530 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3531 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave
[junit4:junit4]   2> 3531 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 3550 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3551 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3551 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave
[junit4:junit4]   2> 3552 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/solr.xml
[junit4:junit4]   2> 3552 T273 oasc.CoreContainer.<init> New CoreContainer 332105300
[junit4:junit4]   2> 3553 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 3554 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 3554 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 3577 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3598 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1
[junit4:junit4]   2> 3599 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/'
[junit4:junit4]   2> 3640 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3694 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 3696 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 3700 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3720 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3722 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/
[junit4:junit4]   2> 3722 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 3723 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/
[junit4:junit4]   2> 3725 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3725 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3726 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3726 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3727 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3727 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3729 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3732 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index forceNew:false
[junit4:junit4]   2> 3734 T273 oass.SolrIndexSearcher.<init> Opening Searcher@47b64deb main
[junit4:junit4]   2> 3735 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3736 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3736 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3737 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3737 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3738 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3738 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3739 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3739 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3740 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3740 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3741 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3753 T273 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 3754 T315 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47b64deb main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3754 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3755 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3755 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3756 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C35 name=collection1 org.apache.solr.core.SolrCore@55a07c1c
[junit4:junit4]   2> 3774 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3878 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 3982 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4087 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 4190 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4294 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4398 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4502 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> 4606 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=0 
[junit4:junit4]   2> ASYNC  NEW_CORE C36 name=collection1 org.apache.solr.core.SolrCore@322e0dcb
[junit4:junit4]   2> 4662 T298 C36 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 4663 T316 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4:junit4]   2> 4664 T316 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4:junit4]   2> 4664 T316 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 4670 T298 C36 REQ [collection1] webapp=/solr path=/replication params={command=filelist&generation=2&wt=javabin&qt=/replication&version=2} status=0 QTime=4 
[junit4:junit4]   2> 4672 T316 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 9
[junit4:junit4]   2> 4676 T316 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index.20120905150114019 fullCopy=false
[junit4:junit4]   2> 4687 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.tis&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=3 
[junit4:junit4]   2> 4703 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.nrm&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4708 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.fnm&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4711 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 4712 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.tii&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4716 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.frq&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4720 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4724 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.fdx&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4728 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4732 T298 C36 REQ [collection1] webapp=/solr path=/replication params={file=_0.fdt&command=filecontent&checksum=true&generation=2&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 4734 T316 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 4747 T316 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 4747 T316 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 4748 T316 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index forceNew:true
[junit4:junit4]   2> 4756 T316 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.nrm, _0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 4756 T316 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 4757 T316 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 4760 T316 oass.SolrIndexSearcher.<init> Opening Searcher@6a063430 main
[junit4:junit4]   2> 4761 T315 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6a063430 main{StandardDirectoryReader(segments_2:4:nrt _0(4.0.0.2):C10)}
[junit4:junit4]   2> 4761 T315 oasc.CachingDirectoryFactory.close Closing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index
[junit4:junit4]   2> 4762 T316 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 4763 T316 oass.SolrIndexSearcher.<init> Opening Searcher@28bf47df main
[junit4:junit4]   2> 4763 T316 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 4763 T315 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28bf47df main{StandardDirectoryReader(segments_2:4:nrt _0(4.0.0.2):C10)}
[junit4:junit4]   2> 4764 T316 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index.20120905150114019
[junit4:junit4]   2> 4816 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 4934 T299 C36 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4937 T299 C36 REQ [collection1] webapp=/solr path=/replication params={command=commits&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 4951 T299 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1
[junit4:junit4]   2> 4952 T299 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/'
[junit4:junit4]   2> 5013 T299 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 5063 T299 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5065 T299 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5069 T299 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5090 T299 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5092 T299 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/
[junit4:junit4]   2> 5093 T299 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 5093 T299 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index/
[junit4:junit4]   2> 5095 T299 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5096 T299 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5096 T299 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5097 T299 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5097 T299 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5097 T299 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5101 T299 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5110 T299 oass.SolrIndexSearcher.<init> Opening Searcher@76e44d85 main
[junit4:junit4]   2> 5111 T299 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5111 T299 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5112 T299 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 5112 T299 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 5112 T299 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 5113 T299 oasu.DefaultSolrCoreState.newIndexWriter Rollback old IndexWriter... core=collection1
[junit4:junit4]   2> 5115 T299 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index forceNew:true
[junit4:junit4]   2> 5119 T299 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.nrm, _0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 5119 T299 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 5120 T299 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 5120 T299 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 5121 T318 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@76e44d85 main{StandardDirectoryReader(segments_2:4 _0(4.0.0.2):C10)}
[junit4:junit4]   2> 5121 T299 oasc.CoreContainer.register replacing core: collection1
[junit4:junit4]   2> 5121 T299 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@322e0dcb
[junit4:junit4]   2> 5122 T299 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 5122 T299 oasc.CachingDirectoryFactory.close Closing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index
[junit4:junit4]   2> 5123 T299 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:junit4]   2> ASYNC  NEW_CORE C37 name=collection1 org.apache.solr.core.SolrCore@3f35fd95
[junit4:junit4]   2> 5130 T299 C37 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5133 T299 C37 REQ [collection1] webapp=/solr path=/replication params={command=commits&qt=/replication&wt=javabin&version=2} status=0 QTime=0 
[junit4:junit4]   2> 5140 T299 C37 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[110]} 0 3
[junit4:junit4]   2> 5144 T299 C37 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[120]} 0 0
[junit4:junit4]   2> 5148 T299 C37 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5199 T299 C37 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.nrm, _0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.tis, _0.nrm, _0.fnm, _1.tis, _1.frq, _1.fnm, _1.fdx, _0.tii, _1.fdt, _1.si, _0.frq, _1.tii, _0.fdx, _0.si, _1.nrm, segments_3, _0.fdt]
[junit4:junit4]   2> 5200 T299 C37 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 5212 T299 C37 oass.SolrIndexSearcher.<init> Opening Searcher@25c47dd5 main
[junit4:junit4]   2> 5213 T299 C37 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5213 T318 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25c47dd5 main{StandardDirectoryReader(segments_3:6 _0(4.0.0.2):C10 _1(4.0.0.2):C2)}
[junit4:junit4]   2> 5214 T299 C37 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 67
[junit4:junit4]   2> 5218 T299 C37 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 5324 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 5430 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 5536 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 5641 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 5658 T298 C37 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=1 
[junit4:junit4]   2> 5659 T316 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4:junit4]   2> 5659 T316 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4:junit4]   2> 5659 T316 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 5665 T298 C37 REQ [collection1] webapp=/solr path=/replication params={command=filelist&generation=3&wt=javabin&qt=/replication&version=2} status=0 QTime=3 
[junit4:junit4]   2> 5666 T316 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 17
[junit4:junit4]   2> 5673 T316 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index.20120905150115014 fullCopy=false
[junit4:junit4]   2> 5674 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.nrm
[junit4:junit4]   2> 5674 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.tis
[junit4:junit4]   2> 5677 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.tis&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5679 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.fnm
[junit4:junit4]   2> 5683 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.frq&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5688 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.fnm&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5693 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.fdx&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5695 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.tii
[junit4:junit4]   2> 5698 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.fdt&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5703 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5705 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.frq
[junit4:junit4]   2> 5706 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.fdx
[junit4:junit4]   2> 5709 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.tii&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5711 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.si
[junit4:junit4]   2> 5712 T316 oash.SnapPuller.downloadIndexFiles Skipping download for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/_0.fdt
[junit4:junit4]   2> 5715 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5720 T298 C37 REQ [collection1] webapp=/solr path=/replication params={file=_1.nrm&command=filecontent&checksum=true&generation=3&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 5722 T316 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 5730 T316 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 5731 T316 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 5731 T316 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1
[junit4:junit4]   2> 5732 T316 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index forceNew:true
[junit4:junit4]   2> 5744 T316 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.nrm, _0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.tis, _0.nrm, _0.fnm, _1.tis, _1.frq, _1.fnm, _1.fdx, _0.tii, _1.fdt, _1.si, _0.frq, _1.tii, _0.fdx, _0.si, _1.nrm, segments_3, _0.fdt]
[junit4:junit4]   2> 5745 T316 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 5746 T316 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 5746 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   2> 5752 T316 oass.SolrIndexSearcher.<init> Opening Searcher@370e3a00 main
[junit4:junit4]   2> 5753 T315 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@370e3a00 main{StandardDirectoryReader(segments_3:6:nrt _0(4.0.0.2):C10 _1(4.0.0.2):C2)}
[junit4:junit4]   2> 5754 T316 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 5755 T316 oass.SolrIndexSearcher.<init> Opening Searcher@4e4dd5a1 main
[junit4:junit4]   2> 5755 T316 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 5756 T315 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e4dd5a1 main{StandardDirectoryReader(segments_3:6:nrt _0(4.0.0.2):C10 _1(4.0.0.2):C2)}
[junit4:junit4]   2> 5756 T316 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index.20120905150115014
[junit4:junit4]   2> 5852 T308 C35 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 5960 T273 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1101213924
[junit4:junit4]   2> 5961 T273 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3f35fd95
[junit4:junit4]   2> 5961 T273 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 5962 T273 oasc.CachingDirectoryFactory.close Closing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index
[junit4:junit4]   2> 5963 T273 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 5964 T273 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 5965 T273 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 5966 T273 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 6018 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6021 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26435
[junit4:junit4]   2> 6022 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6022 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master
[junit4:junit4]   2> 6023 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 6061 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6062 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6062 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master
[junit4:junit4]   2> 6063 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/solr.xml
[junit4:junit4]   2> 6063 T273 oasc.CoreContainer.<init> New CoreContainer 1810150717
[junit4:junit4]   2> 6064 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 6064 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 6065 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/'
[junit4:junit4]   2> 6089 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6109 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1
[junit4:junit4]   2> 6110 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/'
[junit4:junit4]   2> 6150 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6200 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6201 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6205 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6226 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6227 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/
[junit4:junit4]   2> 6228 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6229 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index/
[junit4:junit4]   2> 6230 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6231 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6232 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6232 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6232 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6233 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6237 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6240 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index forceNew:false
[junit4:junit4]   2> 6252 T273 oass.SolrIndexSearcher.<init> Opening Searcher@6d176e5c main
[junit4:junit4]   2> 6253 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6253 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6254 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6254 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6255 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6255 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6256 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6256 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6257 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6257 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6258 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6258 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6274 T273 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 6275 T273 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 6275 T329 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6d176e5c main{StandardDirectoryReader(segments_3:6 _0(4.0.0.2):C10 _1(4.0.0.2):C2)}
[junit4:junit4]   2> 6275 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6276 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 6277 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6277 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6292 T273 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=332105300
[junit4:junit4]   2> 6293 T273 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@55a07c1c
[junit4:junit4]   2> 6294 T273 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 6294 T273 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 6295 T273 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 6297 T273 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 6298 T273 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 6350 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6353 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26436
[junit4:junit4]   2> 6353 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6354 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave
[junit4:junit4]   2> 6355 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 6371 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6372 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6372 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave
[junit4:junit4]   2> 6373 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/solr.xml
[junit4:junit4]   2> 6373 T273 oasc.CoreContainer.<init> New CoreContainer 601741372
[junit4:junit4]   2> 6374 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 6374 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 6375 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/'
[junit4:junit4]   2> 6394 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6413 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1
[junit4:junit4]   2> 6414 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/'
[junit4:junit4]   2> 6453 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6503 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6504 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6508 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6526 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6528 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/
[junit4:junit4]   2> 6528 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6529 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index/
[junit4:junit4]   2> 6531 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6531 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6532 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6532 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6533 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6533 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6534 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6539 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index forceNew:false
[junit4:junit4]   2> 6550 T273 oass.SolrIndexSearcher.<init> Opening Searcher@b077c9d main
[junit4:junit4]   2> 6551 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6551 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6552 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6553 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6553 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6553 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6554 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6554 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6555 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6555 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6556 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6557 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6567 T273 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 6568 T339 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b077c9d main{StandardDirectoryReader(segments_3:6 _0(4.0.0.2):C10 _1(4.0.0.2):C2)}
[junit4:junit4]   2> 6568 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6569 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 6569 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6570 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C38 name=collection1 org.apache.solr.core.SolrCore@7a7baddd
[junit4:junit4]   2> 6590 T322 C38 REQ [collection1] webapp=/solr path=/replication params={command=details&qt=/replication&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 6606 T323 C38 REQ [collection1] webapp=/solr path=/replication params={command=details&slave=false&qt=/replication&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> ASYNC  NEW_CORE C39 name=collection1 org.apache.solr.core.SolrCore@49160709
[junit4:junit4]   2> 6607 T332 C39 REQ [collection1] webapp=/solr path=/replication params={command=details&qt=/replication&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 6621 T273 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6623 T273 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:26440
[junit4:junit4]   2> 6624 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6625 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater
[junit4:junit4]   2> 6625 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/'
[junit4:junit4]   2> 6642 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6643 T273 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6644 T273 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater
[junit4:junit4]   2> 6644 T273 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/solr.xml
[junit4:junit4]   2> 6644 T273 oasc.CoreContainer.<init> New CoreContainer 1191577830
[junit4:junit4]   2> 6645 T273 oasc.CoreContainer$Initializer.initialize no solr.xml file found - using default
[junit4:junit4]   2> 6646 T273 oasc.CoreContainer.load Loading CoreContainer using Solr Home: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/'
[junit4:junit4]   2> 6646 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/'
[junit4:junit4]   2> 6656 T323 C38 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 6667 T273 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6690 T273 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1
[junit4:junit4]   2> 6691 T273 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/'
[junit4:junit4]   2> 6730 T273 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6781 T273 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6782 T273 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6786 T273 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6804 T273 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6806 T273 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/, dataDir=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/data/
[junit4:junit4]   2> 6806 T273 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4:junit4]   2> 6807 T273 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/data/index/
[junit4:junit4]   2> 6808 T273 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6809 T273 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/data/index forceNew:false
[junit4:junit4]   2> 6813 T273 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846475956/repeater/collection1/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6814 T273 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 6815 T273 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6816 T273 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6816 T273 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6816 T273 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6817 T273 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6818 T273 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6819 T273 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6823 T273 oass.SolrIndexSearcher.<init> Opening Searcher@663aa59d main
[junit4:junit4]   2> 6824 T273 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6824 T273 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6825 T273 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6825 T273 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6826 T273 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6826 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6827 T273 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6827 T273 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6828 T273 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6828 T273 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6829 T273 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6829 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6840 T273 oash.SnapPuller.<init> WARNING 'masterUrl' must be specified without the /replication suffix
[junit4:junit4]   2> 6841 T273 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4:junit4]   2> 6841 T273 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4:junit4]   2> 6842 T273 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 6842 T350 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@663aa59d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6842 T273 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6844 T273 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1
[junit4:junit4]   2> 6844 T273 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6845 T273 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6868 T323 C38 REQ [collection1] webapp=/solr path=/replication params={command=details&slave=false&qt=/replication&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> ASYNC  NEW_CORE C40 name=collection1 org.apache.solr.core.SolrCore@74da0c91
[junit4:junit4]   2> 6869 T343 C40 REQ [collection1] webapp=/solr path=/replication params={command=details&qt=/replication&wt=javabin&version=2} status=0 QTime=9 
[junit4:junit4]   2> 6871 T273 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1191577830
[junit4:junit4]   2> 6872 T273 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@74da0c91
[junit4:junit4]   2> 6873 T273 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 6873 T273 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:junit4]   2> 6874 T273 oasu.DefaultSolrCoreState.decref SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 6874 T273 oasu.DefaultSolrCoreState.decref Closing SolrCoreState - canceling any ongoing recovery
[junit4:junit4]   2> 6875 T273 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 6935 T322 C38 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 6940 T322 C38 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4:junit4]   2> 6952 T322 C38 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_2,generation=2,filenames=[_0.nrm, _0.tis, _0.fnm, _0.tii, _0.frq, segments_2, _0.fdx, _0.si, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.tis, _0.nrm, _0.fnm, _1.tis, _1.frq, _1.fnm, _1.fdx, _0.tii, _1.fdt, _1.si, _0.frq, _1.tii, _0.fdx, _0.si, _1.nrm, segments_3, _0.fdt]
[junit4:junit4]   2> 6953 T322 C38 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 6960 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 21
[junit4:junit4]   2> 6964 T322 C38 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 6977 T322 C38 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.tis, _0.nrm, _0.fnm, _1.tis, _1.frq, _1.fnm, _1.fdx, _0.tii, _1.fdt, _1.si, _0.frq, _1.tii, _0.fdx, _0.si, _1.nrm, segments_3, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846469347/master/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 6978 T322 C38 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 6989 T322 C38 oass.SolrIndexSearcher.<init> Opening Searcher@22f1d7a main
[junit4:junit4]   2> 6989 T322 C38 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 6989 T329 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@22f1d7a main{StandardDirectoryReader(segments_4:7)}
[junit4:junit4]   2> 6991 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 27
[junit4:junit4]   2> 6995 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7100 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7206 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7312 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7418 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7524 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7630 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=12 status=0 QTime=1 
[junit4:junit4]   2> 7656 T323 C38 REQ [collection1] webapp=/solr path=/replication params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=0 
[junit4:junit4]   2> 7657 T340 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4:junit4]   2> 7658 T340 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4:junit4]   2> 7658 T340 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4:junit4]   2> 7663 T323 C38 REQ [collection1] webapp=/solr path=/replication params={command=filelist&generation=4&wt=javabin&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   2> 7664 T340 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 1
[junit4:junit4]   2> 7665 T340 oash.SnapPuller.fetchLatestIndex Starting download to ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index.20120905150117011 fullCopy=false
[junit4:junit4]   2> 7669 T323 C38 REQ [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&compression=true&generation=4&qt=/replication&wt=filestream} status=0 QTime=0 
[junit4:junit4]   2> 7674 T340 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4:junit4]   2> 7701 T340 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4:junit4]   2> 7701 T340 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   2> 7703 T340 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index forceNew:true
[junit4:junit4]   2> 7713 T340 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_3,generation=3,filenames=[_0.tis, _0.nrm, _0.fnm, _1.tis, _1.frq, _1.fnm, _1.fdx, _0.tii, _1.fdt, _1.si, _0.frq, _1.tii, _0.fdx, _0.si, _1.nrm, segments_3, _0.fdt]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index,segFN=segments_4,generation=4,filenames=[segments_4]
[junit4:junit4]   2> 7714 T340 oasc.SolrDeletionPolicy.updateCommits newest commit = 4
[junit4:junit4]   2> 7721 T340 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4:junit4]   2> 7722 T340 oass.SolrIndexSearcher.<init> Opening Searcher@28cc5c6c main
[junit4:junit4]   2> 7722 T339 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28cc5c6c main{StandardDirectoryReader(segments_4:7:nrt)}
[junit4:junit4]   2> 7723 T339 oasc.CachingDirectoryFactory.close Closing directory:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build/solr-core/test/J1/./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index
[junit4:junit4]   2> 7724 T340 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 7725 T340 oass.SolrIndexSearcher.<init> Opening Searcher@3cf94dad main
[junit4:junit4]   2> 7725 T340 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 7725 T339 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3cf94dad main{StandardDirectoryReader(segments_4:7:nrt)}
[junit4:junit4]   2> 7726 T340 oash.SnapPuller.fetchLatestIndex removing temporary index download directory ./org.apache.solr.handler.TestReplicationHandler$SolrInstance-1346846470762/slave/collection1/data/index.20120905150117011
[junit4:junit4]   2> 7735 T332 C39 REQ [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&version=2} hits=0 status=0 QTime=1 
[junit4:junit4]   2> 7842 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 2
[junit4:junit4]   2> 7846 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4:junit4]   2> 7850 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4:junit4]   2> 7854 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 0 1
[junit4:junit4]   2> 7858 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 0 1
[junit4:junit4]   2> 7862 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[5]} 0 1
[junit4:junit4]   2> 7866 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[6]} 0 1
[junit4:junit4]   2> 7870 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[7]} 0 0
[junit4:junit4]   2> 7874 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[8]} 0 1
[junit4:junit4]   2> 7878 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[9]} 0 1
[junit4:junit4]   2> 7882 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[10]} 0 1
[junit4:junit4]   2> 7886 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[11]} 0 0
[junit4:junit4]   2> 7890 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[12]} 0 1
[junit4:junit4]   2> 7894 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[13]} 0 1
[junit4:junit4]   2> 7898 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[14]} 0 1
[junit4:junit4]   2> 7902 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[15]} 0 1
[junit4:junit4]   2> 7906 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[16]} 0 1
[junit4:junit4]   2> 7910 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[17]} 0 0
[junit4:junit4]   2> 7914 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[18]} 0 1
[junit4:junit4]   2> 7918 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[19]} 0 0
[junit4:junit4]   2> 7922 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[20]} 0 1
[junit4:junit4]   2> 7926 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[21]} 0 1
[junit4:junit4]   2> 7930 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[22]} 0 0
[junit4:junit4]   2> 7934 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[23]} 0 1
[junit4:junit4]   2> 7938 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[24]} 0 1
[junit4:junit4]   2> 7942 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[25]} 0 1
[junit4:junit4]   2> 7946 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[26]} 0 1
[junit4:junit4]   2> 7950 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[27]} 0 1
[junit4:junit4]   2> 7954 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[28]} 0 1
[junit4:junit4]   2> 7958 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[29]} 0 1
[junit4:junit4]   2> 7962 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[30]} 0 1
[junit4:junit4]   2> 7966 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[31]} 0 1
[junit4:junit4]   2> 7970 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[32]} 0 1
[junit4:junit4]   2> 7974 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[33]} 0 1
[junit4:junit4]   2> 7978 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[34]} 0 1
[junit4:junit4]   2> 7982 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[35]} 0 1
[junit4:junit4]   2> 7986 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[36]} 0 0
[junit4:junit4]   2> 7990 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[37]} 0 1
[junit4:junit4]   2> 7994 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[38]} 0 1
[junit4:junit4]   2> 7998 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[39]} 0 1
[junit4:junit4]   2> 8002 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[40]} 0 1
[junit4:junit4]   2> 8006 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[41]} 0 0
[junit4:junit4]   2> 8010 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[42]} 0 1
[junit4:junit4]   2> 8014 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[43]} 0 1
[junit4:junit4]   2> 8018 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[44]} 0 1
[junit4:junit4]   2> 8022 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[45]} 0 1
[junit4:junit4]   2> 8026 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[46]} 0 1
[junit4:junit4]   2> 8030 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[47]} 0 1
[junit4:junit4]   2> 8034 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[48]} 0 1
[junit4:junit4]   2> 8038 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[49]} 0 1
[junit4:junit4]   2> 8042 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[50]} 0 1
[junit4:junit4]   2> 8046 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[51]} 0 1
[junit4:junit4]   2> 8050 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[52]} 0 1
[junit4:junit4]   2> 8054 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[53]} 0 1
[junit4:junit4]   2> 8058 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[54]} 0 1
[junit4:junit4]   2> 8062 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[55]} 0 1
[junit4:junit4]   2> 8066 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[56]} 0 1
[junit4:junit4]   2> 8070 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[57]} 0 1
[junit4:junit4]   2> 8074 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[58]} 0 1
[junit4:junit4]   2> 8078 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[59]} 0 1
[junit4:junit4]   2> 8082 T322 C38 UPDATE [collection1] webapp=/solr path=/update params={wt=javabin&version

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

0.0.1:42230
[junit4:junit4]   2> 26898 T1903 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost/127.0.0.1:42230
[junit4:junit4]   2> 33633 T1903 oaz.ClientCnxn$SendThread.run Client session timed out, have not heard from server in 8001ms for sessionid 0x139965653470007, closing socket connection and attempting reconnect
[junit4:junit4]   2> 33735 T1791 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 33735 T1757 oaz.ZooKeeper.close Session: 0x139965653470003 closed
[junit4:junit4]   2> 33735 T1757 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 33788 T1757 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 42243
[junit4:junit4]   2> 33788 T1757 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=964310911
[junit4:junit4]   2> 34971 T1903 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost/127.0.0.1:42230
[junit4:junit4]   2> 41836 T1904 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 41836 T1757 oaz.ZooKeeper.close Session: 0x139965653470007 closed
[junit4:junit4]   2> 41837 T1757 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 41958 T1757 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=67BCDAD12F37D995 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_TN -Dtests.timezone=America/Indiana/Indianapolis -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   42.0s J1 | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://localhost:42243/solr/collection1 returned non ok status:404, message:Can not find: /solr/collection1/select
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([67BCDAD12F37D995:E65A54C95868B9A9]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:373)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:182)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.QueryRequest.process(QueryRequest.java:90)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.query(SolrServer.java:324)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:92)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:691)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:616)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:40)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:679)
[junit4:junit4]   2> 41978 T1757 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Appending, sim=DefaultSimilarity, locale=ar_TN, timezone=America/Indiana/Indianapolis
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=1,free=166178504,total=252903424
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SoftAutoCommitTest, BasicZkTest, TestReload, XmlUpdateRequestHandlerTest, SolrCoreTest, LeaderElectionTest, TestSolrJ, TestValueSourceCache, TestReplicationHandler, ClusterStateTest, TestBM25SimilarityFactory, RAMDirectoryFactoryTest, TestDistributedGrouping, DistributedQueryElevationComponentTest, TestPluginEnable, TimeZoneUtilsTest, UUIDFieldTest, BasicFunctionalityTest, XsltUpdateRequestHandlerTest, AnalysisAfterCoreReloadTest, DirectSolrSpellCheckerTest, DirectUpdateHandlerTest, TestSolrCoreProperties, UpdateParamsTest, TestStressReorder, FieldMutatingUpdateProcessorTest, FullSolrCloudDistribCmdsTest, TestSort, HighlighterTest, TestSolrDeletionPolicy1, TestFastOutputStream, RequiredFieldsTest, StatsComponentTest, OutputWriterTest, LukeRequestHandlerTest, IndexReaderFactoryTest, SolrIndexConfigTest, TestLRUCache, PeerSyncTest, TestRealTimeGet, TestTrie, LegacyHTMLStripCharFilterTest, ShowFileRequestHandlerTest, EchoParamsTest, TestSolrXMLSerializer, ZkCLITest, TestJmxIntegration, TestCSVLoader, TestBinaryField, CoreContainerCoreInitFailuresTest, MultiTermTest, TestHashPartitioner, QueryEqualityTest, TestRecovery, PrimitiveFieldTypeTest, DisMaxRequestHandlerTest, TestFoldingMultitermQuery, OverseerTest, RecoveryZkTest]
[junit4:junit4] Completed on J1 in 42.01s, 1 test, 1 error <<< FAILURES!

[...truncated 395 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/build.xml:29: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/solr/build.xml:146: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/module-build.xml:63: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/common-build.xml:1079: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java6/lucene/common-build.xml:756: There were test failures: 228 suites, 910 tests, 1 suite-level error, 1 error, 1 failure, 6 ignored (1 assumption)

Total time: 32 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message