lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk6) - Build # 2024 - Still Failing!
Date Sat, 27 Oct 2012 07:04:31 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2024/
Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

2 tests failed.
REGRESSION:  org.apache.solr.cloud.SyncSliceTest.testDistribSearch

Error Message:
Shard still reported as live in zk - 0 jetty

Stack Trace:
java.lang.AssertionError: Shard still reported as live in zk - 0 jetty
	at __randomizedtesting.SeedInfo.seed([D24AB1D54ACF2E7C:53AC3FCD3D904E40]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1231)
	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1216)
	at org.apache.solr.cloud.SyncSliceTest.waitToSeeDownInClusterState(SyncSliceTest.java:259)
	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:163)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
	at java.lang.reflect.Method.invoke(Method.java:611)
	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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:738)


REGRESSION:  org.apache.solr.core.TestJmxIntegration.testJmxUpdate

Error Message:
No mbean found for SolrIndexSearcher

Stack Trace:
java.lang.AssertionError: No mbean found for SolrIndexSearcher
	at __randomizedtesting.SeedInfo.seed([D24AB1D54ACF2E7C:C42D83BFDA1985D7]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.junit.Assert.assertFalse(Assert.java:68)
	at org.apache.solr.core.TestJmxIntegration.testJmxUpdate(TestJmxIntegration.java:99)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
	at java.lang.reflect.Method.invoke(Method.java:611)
	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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:738)




Build Log:
[...truncated 7736 lines...]
[junit4:junit4] Suite: org.apache.solr.core.TestJmxIntegration
[junit4:junit4]   2> 2 T92 oas.SolrTestCaseJ4.initCore ####initCore
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853
[junit4:junit4]   2> 9 T92 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 11 T92 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 12 T92 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 49 T92 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 122 T92 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 123 T92 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 129 T92 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 953 T92 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 961 T92 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 965 T92 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 972 T92 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1007 T92 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1011 T92 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1012 T92 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1014 T92 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 1014 T92 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 1015 T92 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1023 T92 oasc.CoreContainer.<init> New CoreContainer 714353300
[junit4:junit4]   2> 1023 T92 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853/
[junit4:junit4]   2> 1024 T92 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX.
[junit4:junit4]   2> 1025 T92 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 1026 T92 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@1d981d98 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1ee11ee1) assuming 'simple'
[junit4:junit4]   2> 1026 T92 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853 forceNew:false
[junit4:junit4]   2> 1027 T92 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853
[junit4:junit4]   2> 1027 T92 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853/index/
[junit4:junit4]   2> 1028 T92 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1029 T92 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853/index forceNew:false
[junit4:junit4]   2> 1031 T92 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@3e363e36 lockFactory=org.apache.lucene.store.NativeFSLockFactory@4e6f4e6f; maxCacheMB=0.833984375 maxMergeSizeMB=0.8076171875)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1032 T92 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1033 T92 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853/index
[junit4:junit4]   2> 1034 T92 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1035 T92 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1035 T92 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1036 T92 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1037 T92 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1045 T92 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1045 T92 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1046 T92 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1047 T92 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1047 T92 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1048 T92 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1049 T92 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1049 T92 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1050 T92 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1050 T92 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1051 T92 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1068 T92 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1069 T92 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1069 T92 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1070 T92 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1070 T92 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1071 T92 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1071 T92 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1072 T92 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1072 T92 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1073 T92 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1077 T92 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1080 T92 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1081 T92 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, SCHEMA-REQUIRED-FIELDS.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, SOLRCONFIG-PROPINJECT.XML, SOLRCONFIG-PROPINJECT-INDEXDEFAULT.XML, STOP-2.TXT, SCHEMA11.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, SCHEMA15.XML, SOLRCONFIG-NATIVELOCK.XML, SCHEMA-SIM.XML, CONDITIONAL.UPDATEPROCESSOR.JS, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, SOLRCONFIG-REPEATER.XML, SOLRCONFIG-SPELLCHECKER.XML, SOLRCONFIG-NOCACHE.XML, SCHEMA-CHARFILTERS.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, SOLRCONFIG_CODEC.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML, HYPHENATION.DTD, TRIVIAL.UPDATEPROCESSOR1.JS, SCHEMA.XML, SOLRCONFIG-MERGEPOLICY.XML, SOLRCONFIG-PHRASESUGGEST.XML, SOLRCONFIG-SLAVE1.XML, SOLRCONFIG-BASIC.XML, SCHEMA-LMJELINEKMERCER.XML, JASUGGEST.TXT, BAD-MP-SOLRCONFIG.XML, BAD-SCHEMA-DUP-FIELD.XML, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SOLRCONFIG-MASTER.XML, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-TFIDF.XML, SCHEMA-REPLICATION1.XML, SYNONYMS.TXT, SOLRCONFIG-ELEVATE.XML, STOPWITHBOM.TXT, SOLRCONFIG-QUERYSENDER.XML, WDFTYPES.TXT, SOLRCONFIG-SNIPPET-PROCESSOR.XML, SOLRCONFIG-DELPOLICY1.XML, BAD_SOLRCONFIG.XML, HUNSPELL-TEST.DIC, SCHEMA-NUMERIC.XML, SCHEMA-LUCENEMATCHVERSION.XML, KEEP-2.TXT, SCHEMA-STOP-KEEP.XML, SCHEMA-IB.XML, STOP-SNOWBALL.TXT, SOLRCONFIG-INDEXCONFIG.XML, SOLRCONFIG-SLAVE.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, STOPWORDSWRONGENCODING.TXT, THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, STOPTYPES-1.TXT, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, SOLRCONFIG-TLOG.XML, COMPOUNDDICTIONARY.TXT, DA_COMPOUNDDICTIONARY.TXT, SOLRCONFIG-TERMINDEX.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SCHEMASURROUND.XML, OPEN-EXCHANGE-RATES.JSON, BAD-SCHEMA-DUP-FIELDTYPE.XML, PHRASESUGGEST.TXT, BAD-SCHEMA-CURRENCY-FT-MULTIVALUED.XML, SOLRCONFIG-MASTER2.XML, OLD_SYNONYMS.TXT, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, SCHEMA-LMDIRICHLET.XML, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, SCHEMA-REVERSED.XML, BAD-SCHEMA-CURRENCY-MULTIVALUED.XML, SCHEMA-FOLDING.XML, SOLRCONFIG-CACHING.XML, MAPPING-ISOLATIN1ACCENT.TXT, ADDFIELDS.UPDATEPROCESSOR.JS, SCHEMA-BM25.XML, STOP-1.TXT, SOLRCONFIG-ENABLEPLUGIN.XML, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, SCHEMA12.XML, SOLRCONFIG-TRANSFORMERS.XML, SCHEMA-COPYFIELD-TEST.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, CURRENCY.XML, DA_UTF8.XML, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SOLRCONFIG-SOLR-749.XML, STOPWORDS.TXT, HUNSPELL-TEST.AFF, SOLRCONFIG_PERF.XML, SOLRCONFIG-SIMPLELOCK.XML, FRENCHARTICLES.TXT, SOLRCONFIG-LAZYWRITER.XML, SOLRCONFIG-RESPONSE-LOG-COMPONENT.XML, SCHEMA-REPLICATION2.XML, SCHEMA-SNIPPET-TYPE.XML, SCHEMA-SPATIAL.XML, SOLRCONFIG-XINCLUDE.XML, SCHEMA-SPELLCHECKER.XML, XSLT, SOLRCONFIG-DELPOLICY2.XML, TRIVIAL.UPDATEPROCESSOR0.JS, KEEP-1.TXT, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, SCHEMA-TRIE.XML, SCHEMA-BINARYFIELD.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, SCHEMA-MINIMAL.XML, STOPTYPES-2.TXT, SCHEMA_CODEC.XML, STEMDICT.TXT, ELEVATE.XML, SCHEMA-COLLATE.XML, PROTWORDS.TXT, SCHEMA-XINCLUDE.XML, SCHEMA-SNIPPET-FIELD.XML, SOLRCONFIG-REQHANDLER.INCL, SCHEMA-PHRASESUGGEST.XML, SCHEMA-DFR.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SOLRCONFIG-MASTER1.XML, SOLRCONFIG-FUNCTIONQUERY.XML, SOLRCONFIG-MASTER3.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, SOLRCONFIG-ALTDIRECTORY.XML, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML]
[junit4:junit4]   2> 1084 T92 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853
[junit4:junit4]   2> 1088 T92 oass.SolrIndexSearcher.<init> Opening Searcher@55235523 main
[junit4:junit4]   2> 1089 T92 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1089 T92 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1090 T92 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1106 T92 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1191 T92 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1191 T92 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1192 T92 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1192 T92 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1193 T92 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1193 T92 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1194 T92 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1196 T92 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1198 T92 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1200 T92 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1210 T93 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@55235523 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1211 T92 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1213 T92 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4:junit4]   2> 1264 T92 oas.SolrTestCaseJ4.setUp ###Starting testJmxRegistration
[junit4:junit4]   2> 1265 T92 oasc.TestJmxIntegration.testJmxRegistration Servers in testJmxRegistration: [com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6]
[junit4:junit4]   2> 1279 T92 oas.SolrTestCaseJ4.tearDown ###Ending testJmxRegistration
[junit4:junit4]   2> 1295 T92 oas.SolrTestCaseJ4.setUp ###Starting testJmxUpdate
[junit4:junit4]   2> 1296 T92 oasc.TestJmxIntegration.testJmxUpdate Servers in testJmxUpdate: [com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6]
[junit4:junit4]   2> 1297 T92 oasc.TestJmxIntegration.testJmxUpdate {org.apache.solr.handler.StandardRequestHandler=org.apache.solr.handler.StandardRequestHandler@1e491e49, /admin/system=org.apache.solr.handler.admin.SystemInfoHandler@332e332e, spellCheckWithWordbreak_Direct=org.apache.solr.handler.component.SearchHandler@7d3c7d3c, /admin/logging=org.apache.solr.handler.admin.LoggingHandler@6e476e47, org.apache.solr.highlight.RegexFragmenter=org.apache.solr.highlight.RegexFragmenter@5e1d5e1d, /admin/plugins=org.apache.solr.handler.admin.PluginInfoHandler@6e116e11, queryResultCache=queryResultCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}, org.apache.solr.highlight.BreakIteratorBoundaryScanner=org.apache.solr.highlight.BreakIteratorBoundaryScanner@6a1c6a1c, org.apache.solr.highlight.HtmlFormatter=org.apache.solr.highlight.HtmlFormatter@60d660d6, org.apache.solr.handler.RealTimeGetHandler=org.apache.solr.handler.RealTimeGetHandler@3c0e3c0e, org.apache.solr.highlight.GapFragmenter=org.apache.solr.highlight.GapFragmenter@5c005c00, org.apache.solr.highlight.SimpleFragmentsBuilder=org.apache.solr.highlight.SimpleFragmentsBuilder@65106510, Searcher@55235523 main=Searcher@55235523 main{StandardDirectoryReader(segments_1:1)}, /admin/file=org.apache.solr.handler.admin.ShowFileRequestHandler@2f2d2f2d, mock=org.apache.solr.core.MockQuerySenderListenerReqHandler@5c4a5c4a, core=org.apache.solr.core.SolrCore@666d666d, spellCheckWithWordbreak=org.apache.solr.handler.component.SearchHandler@2e092e09, searcher=Searcher@55235523 main{StandardDirectoryReader(segments_1:1)}, spellCheckCompRH1=org.apache.solr.handler.component.SearchHandler@4def4def, org.apache.solr.handler.component.TermVectorComponent=org.apache.solr.handler.component.TermVectorComponent@6ade6ade, /admin/luke=org.apache.solr.handler.admin.LukeRequestHandler@331c331c, /update=org.apache.solr.handler.UpdateRequestHandler@56ea56ea, fieldCache=org.apache.solr.search.SolrFieldCacheMBean@36473647, defaults=org.apache.solr.handler.StandardRequestHandler@1e491e49, org.apache.solr.handler.MoreLikeThisHandler=org.apache.solr.handler.MoreLikeThisHandler@6e976e97, /terms=org.apache.solr.handler.component.SearchHandler@656e656e, /admin/threads=org.apache.solr.handler.admin.ThreadDumpHandler@6e236e23, org.apache.solr.core.MockQuerySenderListenerReqHandler=org.apache.solr.core.MockQuerySenderListenerReqHandler@5c4a5c4a, spellCheckCompRH_Direct=org.apache.solr.handler.component.SearchHandler@34d034d, /replication=org.apache.solr.core.RequestHandlers$LazyRequestHandlerWrapper@18ae18ae, org.apache.solr.handler.UpdateRequestHandler=org.apache.solr.handler.UpdateRequestHandler@56ea56ea, lazy=org.apache.solr.core.RequestHandlers$LazyRequestHandlerWrapper@3dcc3dcc, org.apache.solr.highlight.ScoreOrderFragmentsBuilder=org.apache.solr.highlight.ScoreOrderFragmentsBuilder@66c966c9, org.apache.solr.highlight.SimpleFragListBuilder=org.apache.solr.highlight.SimpleFragListBuilder@63266326, org.apache.solr.handler.component.TermsComponent=org.apache.solr.handler.component.TermsComponent@690e690e, /mlt=org.apache.solr.handler.MoreLikeThisHandler@6e976e97, org.apache.solr.handler.component.RealTimeGetComponent=org.apache.solr.handler.component.RealTimeGetComponent@744e744e, documentCache=documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}, /admin/mbeans=org.apache.solr.handler.admin.SolrInfoMBeanHandler@6dff6dff, org.apache.solr.handler.component.MoreLikeThisComponent=org.apache.solr.handler.component.MoreLikeThisComponent@70357035, dismax=org.apache.solr.handler.component.SearchHandler@bea0bea, org.apache.solr.handler.component.FacetComponent=org.apache.solr.handler.component.FacetComponent@6eec6eec, org.apache.solr.handler.component.HighlightComponent=org.apache.solr.handler.component.HighlightComponent@6c6e6c6e, org.apache.solr.handler.component.SpellCheckComponent=org.apache.solr.handler.component.SpellCheckComponent@65ff65ff, /get=org.apache.solr.handler.RealTimeGetHandler@3c0e3c0e, fieldValueCache=fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}, /admin/=org.apache.solr.handler.admin.AdminHandlers@3e433e43, spellCheckCompRH=org.apache.solr.handler.component.SearchHandler@342b342b, standard=org.apache.solr.handler.StandardRequestHandler@6a426a42, org.apache.solr.handler.admin.AdminHandlers=org.apache.solr.handler.admin.AdminHandlers@3e433e43, tvrh=org.apache.solr.handler.component.SearchHandler@1e061e06, org.apache.solr.handler.DumpRequestHandler=org.apache.solr.handler.DumpRequestHandler@3ef53ef5, org.apache.solr.highlight.SimpleBoundaryScanner=org.apache.solr.highlight.SimpleBoundaryScanner@68736873, org.apache.solr.handler.component.StatsComponent=org.apache.solr.handler.component.StatsComponent@71817181, /admin/properties=org.apache.solr.handler.admin.PropertiesRequestHandler@6e356e35, org.apache.solr.handler.component.QueryComponent=org.apache.solr.handler.component.QueryComponent@6da56da5, org.apache.solr.handler.component.SearchHandler=org.apache.solr.handler.component.SearchHandler@1e061e06, org.apache.solr.handler.component.DebugComponent=org.apache.solr.handler.component.DebugComponent@72cd72cd, /debug/dump=org.apache.solr.handler.DumpRequestHandler@3ef53ef5, filterCache=filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}, updateHandler=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> 1298 T92 oasc.TestJmxIntegration.testJmxUpdate Mbeans in server: [java.lang:type=MemoryPool,name=JIT code cache, java.lang:type=Compilation, java.lang:type=Memory, java.lang:type=GarbageCollector,name=MarkSweepCompact, JMImplementation:type=MBeanServerDelegate, java.lang:type=ClassLoading, java.lang:type=MemoryPool,name=Java heap, java.lang:type=OperatingSystem, java.lang:type=MemoryPool,name=class storage, java.lang:type=Threading, java.lang:type=Runtime, java.util.logging:type=Logging, java.lang:type=MemoryManager,name=J9 non-heap manager, java.lang:type=MemoryPool,name=JIT data cache, java.lang:type=MemoryPool,name=miscellaneous non-heap storage]
[junit4:junit4]   2> 1299 T92 oas.SolrTestCaseJ4.tearDown ###Ending testJmxUpdate
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestJmxIntegration -Dtests.method=testJmxUpdate -Dtests.seed=D24AB1D54ACF2E7C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_HN -Dtests.timezone=Brazil/DeNoronha -Dtests.file.encoding=UTF-8
[junit4:junit4] FAILURE 0.04s J1 | TestJmxIntegration.testJmxUpdate <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: No mbean found for SolrIndexSearcher
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([D24AB1D54ACF2E7C:C42D83BFDA1985D7]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.junit.Assert.assertTrue(Assert.java:43)
[junit4:junit4]    > 	at org.junit.Assert.assertFalse(Assert.java:68)
[junit4:junit4]    > 	at org.apache.solr.core.TestJmxIntegration.testJmxUpdate(TestJmxIntegration.java:99)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:611)
[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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[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:738)
[junit4:junit4]   2> 1338 T92 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 1339 T92 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=714353300
[junit4:junit4]   2> 1339 T92 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@666d666d
[junit4:junit4]   2> 1340 T92 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> 1341 T92 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 1342 T92 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 1342 T92 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 1344 T92 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 1345 T92 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-TestJmxIntegration-1351320837853/index
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=es_HN, timezone=Brazil/DeNoronha
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=2,free=6775280,total=20139008
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SolrInfoMBeanTest, RequiredFieldsTest, UpdateParamsTest, FileUtilsTest, SoftAutoCommitTest, TestReload, TestJmxIntegration]
[junit4:junit4] Completed on J1 in 1.44s, 3 tests, 1 failure, 1 skipped <<< FAILURES!

[...truncated 227 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.SyncSliceTest
[junit4:junit4]   2> 3 T861 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SyncSliceTest-1351321032415
[junit4:junit4]   2> 5 T861 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 7 T862 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 8 T862 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 10 T862 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 106 T861 oasc.ZkTestServer.run start zk server on port:55896
[junit4:junit4]   2> 107 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@6d8a6d8a
[junit4:junit4]   2> 109 T867 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 109 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 111 T867 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 112 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46047
[junit4:junit4]   2> 113 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46047
[junit4:junit4]   2> 114 T865 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 223 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50000 with negotiated timeout 10000 for client /127.0.0.1:46047
[junit4:junit4]   2> 223 T867 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50000, negotiated timeout = 10000
[junit4:junit4]   2> 224 T868 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d8a6d8a name:ZooKeeperConnection Watcher:127.0.0.1:55896 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 225 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 227 T861 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 231 T866 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa10296e50000
[junit4:junit4]   2> 232 T868 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 232 T861 oaz.ZooKeeper.close Session: 0x13aa10296e50000 closed
[junit4:junit4]   2> 233 T863 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:46047 which had sessionid 0x13aa10296e50000
[junit4:junit4]   2> 234 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@779b779b
[junit4:junit4]   2> 237 T869 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 237 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 239 T869 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 240 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46048
[junit4:junit4]   2> 244 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46048
[junit4:junit4]   2> 247 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50001 with negotiated timeout 10000 for client /127.0.0.1:46048
[junit4:junit4]   2> 247 T869 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50001, negotiated timeout = 10000
[junit4:junit4]   2> 250 T870 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@779b779b name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 251 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 252 T861 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 256 T861 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 259 T861 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 262 T861 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 265 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 267 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 273 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 275 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 279 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 281 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 284 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 286 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 288 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 289 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 291 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 291 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 293 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 294 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 317 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 318 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 320 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 321 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 322 T861 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 323 T861 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 324 T866 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aa10296e50001
[junit4:junit4]   2> 325 T870 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 325 T861 oaz.ZooKeeper.close Session: 0x13aa10296e50001 closed
[junit4:junit4]   2> 325 T863 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:46048 which had sessionid 0x13aa10296e50001
[junit4:junit4]   2> 440 T861 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 443 T861 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:37418
[junit4:junit4]   2> 444 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 445 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737
[junit4:junit4]   2> 446 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/'
[junit4:junit4]   2> 457 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 457 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 458 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737
[junit4:junit4]   2> 459 T861 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/solr.xml
[junit4:junit4]   2> 459 T861 oasc.CoreContainer.<init> New CoreContainer 1041055245
[junit4:junit4]   2> 460 T861 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/'
[junit4:junit4]   2> 461 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/'
[junit4:junit4]   2> 482 T861 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 491 T861 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55896/solr
[junit4:junit4]   2> 492 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@69636963
[junit4:junit4]   2> 493 T880 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 493 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 495 T880 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 495 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46049
[junit4:junit4]   2> 496 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46049
[junit4:junit4]   2> 498 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50002 with negotiated timeout 5000 for client /127.0.0.1:46049
[junit4:junit4]   2> 498 T880 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50002, negotiated timeout = 5000
[junit4:junit4]   2> 500 T881 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69636963 name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 501 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 502 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 504 T861 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 505 T861 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37418_solr
[junit4:junit4]   2> 506 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37418_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37418_solr
[junit4:junit4]   2> 507 T861 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37418_solr
[junit4:junit4]   2> 511 T861 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 512 T861 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 513 T861 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 514 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 515 T861 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 516 T861 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 517 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 518 T861 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 520 T861 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 521 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 529 T861 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 533 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 534 T861 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 537 T861 oasc.Overseer.start Overseer (id=88560175180742658-127.0.0.1:37418_solr-n_0000000000) starting
[junit4:junit4]   2> 538 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 539 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 540 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 544 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 546 T883 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 546 T861 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 549 T861 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 552 T882 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 557 T861 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/collection1
[junit4:junit4]   2> 558 T861 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 560 T861 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 561 T861 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 563 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/collection1/'
[junit4:junit4]   2> 566 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/collection1/lib/README' to classloader
[junit4:junit4]   2> 567 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 612 T861 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 650 T861 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 652 T861 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 657 T861 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 1044 T861 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 1049 T861 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 1052 T861 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 1059 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1062 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 1066 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1067 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 1069 T861 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-controljetty-1351321032737/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/
[junit4:junit4]   2> 1069 T861 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6
[junit4:junit4]   2> 1071 T861 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 1072 T861 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data lockFactory=org.apache.lucene.store.NativeFSLockFactory@215f215f; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 1073 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data forceNew:false
[junit4:junit4]   2> 1073 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data
[junit4:junit4]   2> 1074 T861 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/index/
[junit4:junit4]   2> 1075 T861 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 1077 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/index forceNew:false
[junit4:junit4]   2> 1084 T861 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ecb5ecb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 1085 T861 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 1086 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/index
[junit4:junit4]   2> 1086 T861 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 1089 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 1090 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 1090 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 1091 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 1092 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 1093 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 1093 T861 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 1094 T861 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 1095 T861 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 1096 T861 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 1097 T861 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 1098 T861 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 1099 T861 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 1100 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 1101 T861 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 1101 T861 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 1102 T861 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1103 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1104 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1105 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1106 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1107 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1108 T861 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 1109 T861 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 1110 T861 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 1118 T861 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 1121 T861 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 1123 T861 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 1127 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data
[junit4:junit4]   2> 1129 T861 oass.SolrIndexSearcher.<init> Opening Searcher@30063006 main
[junit4:junit4]   2> 1130 T861 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 1131 T861 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 1132 T861 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 1140 T861 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 1202 T884 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@30063006 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 1218 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 1220 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 1223 T861 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 1224 T861 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37418/solr shardId:control_shard
[junit4:junit4]   2> 1226 T861 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 1231 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:delete cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 1232 T861 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 1233 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1556 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 1558 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37418/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37418_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 1559 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x6a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 1566 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 1735 T861 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 1736 T861 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 1737 T861 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:37418/solr/collection1/
[junit4:junit4]   2> 1739 T861 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 1740 T861 oasc.SyncStrategy.syncToMe http://127.0.0.1:37418/solr/collection1/ has no replicas
[junit4:junit4]   2> 1741 T861 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:37418/solr/collection1/
[junit4:junit4]   2> 1742 T861 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 1747 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50002 type:create cxid:0x89 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2071 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2077 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2101 T861 oasc.ZkController.register We are http://127.0.0.1:37418/solr/collection1/ and leader is http://127.0.0.1:37418/solr/collection1/
[junit4:junit4]   2> 2102 T861 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37418/solr
[junit4:junit4]   2> 2103 T861 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 2105 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2107 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2108 T861 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 2108 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 2109 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 2113 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 2117 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@73b873b8
[junit4:junit4]   2> 2118 T885 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 2118 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2119 T885 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 2119 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46052
[junit4:junit4]   2> 2120 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46052
[junit4:junit4]   2> 2121 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50003 with negotiated timeout 10000 for client /127.0.0.1:46052
[junit4:junit4]   2> 2121 T885 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50003, negotiated timeout = 10000
[junit4:junit4]   2> 2122 T886 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73b873b8 name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2123 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2124 T861 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2125 T861 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 2251 T861 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2253 T861 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:44050
[junit4:junit4]   2> 2254 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2255 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537
[junit4:junit4]   2> 2255 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/'
[junit4:junit4]   2> 2267 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2268 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2269 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537
[junit4:junit4]   2> 2269 T861 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/solr.xml
[junit4:junit4]   2> 2270 T861 oasc.CoreContainer.<init> New CoreContainer 1491622120
[junit4:junit4]   2> 2271 T861 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/'
[junit4:junit4]   2> 2271 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/'
[junit4:junit4]   2> 2285 T861 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2294 T861 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55896/solr
[junit4:junit4]   2> 2295 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@55625562
[junit4:junit4]   2> 2296 T896 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 2296 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2298 T896 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 2298 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46053
[junit4:junit4]   2> 2300 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46053
[junit4:junit4]   2> 2302 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50004 with negotiated timeout 5000 for client /127.0.0.1:46053
[junit4:junit4]   2> 2302 T896 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50004, negotiated timeout = 5000
[junit4:junit4]   2> 2304 T897 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@55625562 name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2305 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2313 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2315 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2316 T861 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44050_solr
[junit4:junit4]   2> 2317 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:44050_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:44050_solr
[junit4:junit4]   2> 2320 T861 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44050_solr
[junit4:junit4]   2> 2323 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 2323 T886 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2324 T861 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2324 T881 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 2325 T861 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2326 T861 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2327 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2329 T861 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2331 T861 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2332 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2334 T861 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2336 T861 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2337 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2348 T861 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2354 T861 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/collection1
[junit4:junit4]   2> 2355 T861 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 2356 T861 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2357 T861 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 2358 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/collection1/'
[junit4:junit4]   2> 2359 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/collection1/lib/README' to classloader
[junit4:junit4]   2> 2360 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2390 T861 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2442 T861 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2443 T861 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2448 T861 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2579 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2580 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37418/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37418_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 2582 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2582 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2582 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 2823 T861 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2829 T861 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2832 T861 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2838 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2842 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2846 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2847 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2848 T861 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty1-1351321034537/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/
[junit4:junit4]   2> 2849 T861 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6
[junit4:junit4]   2> 2850 T861 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 2851 T861 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1 lockFactory=org.apache.lucene.store.NativeFSLockFactory@7d617d61; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 2851 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1 forceNew:false
[junit4:junit4]   2> 2852 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1
[junit4:junit4]   2> 2853 T861 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/index/
[junit4:junit4]   2> 2854 T861 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2855 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/index forceNew:false
[junit4:junit4]   2> 2861 T861 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@35d235d2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2861 T861 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2862 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/index
[junit4:junit4]   2> 2864 T861 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2867 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2868 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2869 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2869 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2870 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2871 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2871 T861 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2872 T861 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2873 T861 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2874 T861 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2875 T861 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2876 T861 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2877 T861 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2878 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2879 T861 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2879 T861 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2886 T861 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2887 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2888 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2889 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2890 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2891 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2892 T861 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2892 T861 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2893 T861 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2895 T861 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2896 T861 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2898 T861 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2900 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1
[junit4:junit4]   2> 2901 T861 oass.SolrIndexSearcher.<init> Opening Searcher@3de53de5 main
[junit4:junit4]   2> 2902 T861 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2903 T861 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2904 T861 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2911 T861 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2960 T898 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3de53de5 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2970 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3084 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3086 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44050/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44050_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 3087 T882 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 3091 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3091 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3091 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3472 T861 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3474 T861 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:44050/solr shardId:shard1
[junit4:junit4]   2> 3476 T861 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 3484 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50004 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 3485 T861 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3487 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3489 T861 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3491 T861 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3492 T861 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:44050/solr/collection1/
[junit4:junit4]   2> 3493 T861 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3494 T861 oasc.SyncStrategy.syncToMe http://127.0.0.1:44050/solr/collection1/ has no replicas
[junit4:junit4]   2> 3495 T861 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:44050/solr/collection1/
[junit4:junit4]   2> 3497 T861 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 3502 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3594 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3602 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3602 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3602 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3655 T861 oasc.ZkController.register We are http://127.0.0.1:44050/solr/collection1/ and leader is http://127.0.0.1:44050/solr/collection1/
[junit4:junit4]   2> 3656 T861 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:44050/solr
[junit4:junit4]   2> 3658 T861 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3659 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3662 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3664 T861 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 3665 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3667 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3823 T861 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3826 T861 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:37532
[junit4:junit4]   2> 3827 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3828 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087
[junit4:junit4]   2> 3829 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/'
[junit4:junit4]   2> 3850 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3851 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3852 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087
[junit4:junit4]   2> 3852 T861 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/solr.xml
[junit4:junit4]   2> 3853 T861 oasc.CoreContainer.<init> New CoreContainer 83297527
[junit4:junit4]   2> 3854 T861 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/'
[junit4:junit4]   2> 3854 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/'
[junit4:junit4]   2> 3870 T861 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3886 T861 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55896/solr
[junit4:junit4]   2> 3887 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@7d7f7d7f
[junit4:junit4]   2> 3888 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3889 T908 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 3891 T908 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 3891 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46056
[junit4:junit4]   2> 3892 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46056
[junit4:junit4]   2> 3895 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50005 with negotiated timeout 5000 for client /127.0.0.1:46056
[junit4:junit4]   2> 3895 T908 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50005, negotiated timeout = 5000
[junit4:junit4]   2> 3896 T909 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7d7f7d7f name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3897 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3897 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3898 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3899 T861 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37532_solr
[junit4:junit4]   2> 3900 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37532_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37532_solr
[junit4:junit4]   2> 3901 T861 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37532_solr
[junit4:junit4]   2> 3909 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3909 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3909 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 3909 T861 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3910 T886 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3911 T881 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3911 T861 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3911 T897 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 3919 T861 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3920 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3920 T861 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3921 T861 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3922 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3922 T861 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3923 T861 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3923 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3930 T861 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3935 T861 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/collection1
[junit4:junit4]   2> 3936 T861 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3937 T861 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3937 T861 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3938 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/collection1/'
[junit4:junit4]   2> 3940 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/collection1/lib/README' to classloader
[junit4:junit4]   2> 3940 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3978 T861 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4033 T861 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4034 T861 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4040 T861 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4106 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4113 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:44050/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:44050_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4115 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4115 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4115 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4115 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 4521 T861 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4527 T861 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4530 T861 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4537 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4542 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4552 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4553 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4554 T861 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty2-1351321036087/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/
[junit4:junit4]   2> 4555 T861 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6
[junit4:junit4]   2> 4557 T861 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 4558 T861 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@3bd93bd9; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 4558 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2 forceNew:false
[junit4:junit4]   2> 4559 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2
[junit4:junit4]   2> 4560 T861 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index/
[junit4:junit4]   2> 4561 T861 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4562 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index forceNew:false
[junit4:junit4]   2> 4569 T861 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@24bf24bf; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4570 T861 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 4571 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index
[junit4:junit4]   2> 4572 T861 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4575 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4576 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4576 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4577 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4578 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4579 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4580 T861 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4581 T861 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4582 T861 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4583 T861 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4584 T861 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4585 T861 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4586 T861 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4586 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4587 T861 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4588 T861 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4589 T861 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4591 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4592 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4593 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4594 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4595 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4596 T861 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4597 T861 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4598 T861 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4600 T861 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4601 T861 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4603 T861 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 4605 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2
[junit4:junit4]   2> 4607 T861 oass.SolrIndexSearcher.<init> Opening Searcher@2bc12bc1 main
[junit4:junit4]   2> 4615 T861 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4615 T861 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4617 T861 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4624 T861 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 4675 T910 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2bc12bc1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 4685 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5118 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5119 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37532/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37532_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5121 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5121 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5121 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5121 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5187 T861 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5188 T861 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37532/solr shardId:shard1
[junit4:junit4]   2> 5192 T861 oasc.ZkController.register We are http://127.0.0.1:37532/solr/collection1/ and leader is http://127.0.0.1:44050/solr/collection1/
[junit4:junit4]   2> 5193 T861 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37532/solr
[junit4:junit4]   2> 5195 T861 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 5196 T861 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C89 name=collection1 org.apache.solr.core.SolrCore@3f7d3f7d url=http://127.0.0.1:37532/solr/collection1 node=127.0.0.1:37532_solr
[junit4:junit4]   2> 5198 T911 C89 P37532 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 5198 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5199 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 5201 T911 C89 P37532 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 5201 T861 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 5203 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5204 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 5246 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:44050/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 5246 T911 C89 P37532 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:37532/solr START replicas=[http://127.0.0.1:44050/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 5247 T911 C89 P37532 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 5248 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 5249 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 5249 T911 C89 P37532 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C90 name=collection1 org.apache.solr.core.SolrCore@19e719e7 url=http://127.0.0.1:44050/solr/collection1 node=127.0.0.1:44050_solr
[junit4:junit4]   2> 5254 T892 C90 P44050 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 5361 T861 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 5363 T861 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:37703
[junit4:junit4]   2> 5365 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5366 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620
[junit4:junit4]   2> 5366 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/'
[junit4:junit4]   2> 5382 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 5384 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 5385 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620
[junit4:junit4]   2> 5385 T861 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/solr.xml
[junit4:junit4]   2> 5386 T861 oasc.CoreContainer.<init> New CoreContainer 1234192784
[junit4:junit4]   2> 5387 T861 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/'
[junit4:junit4]   2> 5388 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/'
[junit4:junit4]   2> 5408 T861 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 5434 T861 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55896/solr
[junit4:junit4]   2> 5435 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@29b929b9
[junit4:junit4]   2> 5437 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 5438 T922 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 5439 T922 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 5439 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46067
[junit4:junit4]   2> 5440 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46067
[junit4:junit4]   2> 5443 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50006 with negotiated timeout 5000 for client /127.0.0.1:46067
[junit4:junit4]   2> 5443 T922 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50006, negotiated timeout = 5000
[junit4:junit4]   2> 5444 T923 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29b929b9 name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 5444 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 5445 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50006 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5446 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50006 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5447 T861 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37703_solr
[junit4:junit4]   2> 5448 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50006 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:37703_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:37703_solr
[junit4:junit4]   2> 5449 T861 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:37703_solr
[junit4:junit4]   2> 5450 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5450 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5450 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5450 T861 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 5451 T897 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5452 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 5451 T886 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5452 T881 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5452 T861 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 5453 T909 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4:junit4]   2> 5454 T861 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 5455 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 5456 T861 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 5456 T861 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 5457 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 5462 T861 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 5463 T861 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 5464 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 5474 T861 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 5481 T861 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/collection1
[junit4:junit4]   2> 5482 T861 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 5483 T861 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 5484 T861 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 5485 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/collection1/'
[junit4:junit4]   2> 5486 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/collection1/lib/README' to classloader
[junit4:junit4]   2> 5487 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 5531 T861 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5590 T861 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5591 T861 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5597 T861 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5651 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5652 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37532/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37532_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5655 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5655 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5655 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5655 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 5655 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 6164 T861 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 6169 T861 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 6173 T861 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 6182 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6188 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 6194 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6196 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 6199 T861 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty3-1351321037620/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/
[junit4:junit4]   2> 6200 T861 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6
[junit4:junit4]   2> 6203 T861 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 6205 T861 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5dcc5dcc; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 6206 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3 forceNew:false
[junit4:junit4]   2> 6207 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3
[junit4:junit4]   2> 6208 T861 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index/
[junit4:junit4]   2> 6209 T861 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 6210 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index forceNew:false
[junit4:junit4]   2> 6551 T861 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@78007800; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 6552 T861 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 6554 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index
[junit4:junit4]   2> 6554 T861 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 6557 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 6558 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 6559 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 6559 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 6560 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 6561 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 6562 T861 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 6563 T861 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 6574 T861 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 6576 T861 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 6577 T861 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 6579 T861 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 6581 T861 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 6582 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 6583 T861 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 6585 T861 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 6586 T861 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6588 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6590 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6591 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6593 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6594 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6596 T861 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 6598 T861 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 6599 T861 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 6602 T861 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 6605 T861 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 6616 T861 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 6620 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3
[junit4:junit4]   2> 6623 T861 oass.SolrIndexSearcher.<init> Opening Searcher@1ae21ae2 main
[junit4:junit4]   2> 6625 T861 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 6626 T861 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 6627 T861 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 6643 T861 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 6750 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ae21ae2 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 6762 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7163 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7163 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37703/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37703_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 7454 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7454 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7455 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7455 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7455 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7764 T861 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7765 T861 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:37703/solr shardId:shard1
[junit4:junit4]   2> 7771 T861 oasc.ZkController.register We are http://127.0.0.1:37703/solr/collection1/ and leader is http://127.0.0.1:44050/solr/collection1/
[junit4:junit4]   2> 7773 T861 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:37703/solr
[junit4:junit4]   2> 7775 T861 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 7777 T861 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C91 name=collection1 org.apache.solr.core.SolrCore@1b8a1b8a url=http://127.0.0.1:37703/solr/collection1 node=127.0.0.1:37703_solr
[junit4:junit4]   2> 7808 T925 C91 P37703 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 7808 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7809 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 7810 T925 C91 P37703 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7811 T861 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 7811 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:44050/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 7812 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 7812 T925 C91 P37703 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:37703/solr START replicas=[http://127.0.0.1:44050/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 7814 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7814 T925 C91 P37703 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 7815 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 7815 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 7815 T892 C90 P44050 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 7816 T925 C91 P37703 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 7946 T861 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 7948 T861 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:48407
[junit4:junit4]   2> 7950 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7950 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231
[junit4:junit4]   2> 7951 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/'
[junit4:junit4]   2> 7957 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7958 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37703/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37703_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 7961 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7961 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7961 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7961 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7961 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 7964 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 7965 T861 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 7966 T861 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231
[junit4:junit4]   2> 7966 T861 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/solr.xml
[junit4:junit4]   2> 7967 T861 oasc.CoreContainer.<init> New CoreContainer 152373525
[junit4:junit4]   2> 7968 T861 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/'
[junit4:junit4]   2> 7968 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/'
[junit4:junit4]   2> 7984 T861 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 7995 T861 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:55896/solr
[junit4:junit4]   2> 7996 T861 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:55896/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@298f298f
[junit4:junit4]   2> 7997 T936 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:55896
[junit4:junit4]   2> 7997 T861 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 7999 T936 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:55896, initiating session
[junit4:junit4]   2> 7999 T863 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:46070
[junit4:junit4]   2> 8000 T863 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:46070
[junit4:junit4]   2> 8003 T865 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aa10296e50007 with negotiated timeout 5000 for client /127.0.0.1:46070
[junit4:junit4]   2> 8004 T936 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:55896, sessionid = 0x13aa10296e50007, negotiated timeout = 5000
[junit4:junit4]   2> 8006 T937 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@298f298f name:ZooKeeperConnection Watcher:127.0.0.1:55896/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8006 T861 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8007 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50007 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8008 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50007 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8010 T861 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48407_solr
[junit4:junit4]   2> 8011 T866 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aa10296e50007 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48407_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48407_solr
[junit4:junit4]   2> 8012 T861 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48407_solr
[junit4:junit4]   2> 8013 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8013 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8013 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8013 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8013 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (4)
[junit4:junit4]   2> 8015 T923 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8014 T881 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8015 T861 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8015 T886 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8016 T909 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8017 T861 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8017 T897 oascc.ZkStateReader$3.process Updating live nodes... (5)
[junit4:junit4]   2> 8019 T861 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8020 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8021 T861 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8022 T861 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8024 T861 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8025 T861 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8026 T861 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8027 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8038 T861 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8058 T861 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/collection1
[junit4:junit4]   2> 8059 T861 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8060 T861 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8060 T861 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8061 T861 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/collection1/'
[junit4:junit4]   2> 8063 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/collection1/lib/README' to classloader
[junit4:junit4]   2> 8064 T861 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8099 T861 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 8146 T861 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8157 T861 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8164 T861 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2>  C89_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:37532/solr, state=recovering, node_name=127.0.0.1:37532_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 8258 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 8259 T911 C89 P37532 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 8260 T911 C89 P37532 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:44050/solr/collection1/. core=collection1
[junit4:junit4]   2> 8260 T911 C89 P37532 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8267 T892 C90 P44050 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8268 T892 C90 P44050 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1
[junit4:junit4]   2> 8279 T892 C90 P44050 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@35d235d2; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8280 T892 C90 P44050 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8282 T892 C90 P44050 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1
[junit4:junit4]   2> 8282 T892 C90 P44050 oass.SolrIndexSearcher.<init> Opening Searcher@5d885d88 realtime
[junit4:junit4]   2> 8283 T892 C90 P44050 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8284 T892 C90 P44050 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 18
[junit4:junit4]   2> 8285 T911 C89 P37532 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8286 T911 C89 P37532 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 8288 T895 C90 P44050 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 8289 T895 C90 P44050 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 8290 T911 C89 P37532 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2
[junit4:junit4]   2> 8294 T911 C89 P37532 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@24bf24bf; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8295 T911 C89 P37532 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8296 T911 C89 P37532 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 8310 T911 C89 P37532 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@24bf24bf; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@24bf24bf; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 8311 T911 C89 P37532 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 8313 T911 C89 P37532 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2
[junit4:junit4]   2> 8318 T911 C89 P37532 oass.SolrIndexSearcher.<init> Opening Searcher@14481448 main
[junit4:junit4]   2> 8318 T911 C89 P37532 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 8321 T910 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14481448 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 8322 T910 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty2/index
[junit4:junit4]   2> 8322 T911 C89 P37532 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 8323 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 8323 T911 C89 P37532 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8325 T911 C89 P37532 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 8484 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8485 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37532/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37532_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 8492 T937 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8492 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8492 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8492 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8492 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8492 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 8642 T861 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 8648 T861 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 8651 T861 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 8658 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8662 T861 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 8667 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8668 T861 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 8669 T861 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-jetty4-1351321040231/collection1/, dataDir=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/
[junit4:junit4]   2> 8670 T861 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@cc60cc6
[junit4:junit4]   2> 8671 T861 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4:junit4]   2> 8672 T861 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4 lockFactory=org.apache.lucene.store.NativeFSLockFactory@5dca5dca; maxCacheMB=48.0 maxMergeSizeMB=4.0) assuming 'simple'
[junit4:junit4]   2> 8673 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4 forceNew:false
[junit4:junit4]   2> 8673 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4
[junit4:junit4]   2> 8674 T861 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index/
[junit4:junit4]   2> 8675 T861 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 8676 T861 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index forceNew:false
[junit4:junit4]   2> 8721 T861 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@be90be9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8723 T861 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8724 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index
[junit4:junit4]   2> 8725 T861 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 8730 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 8731 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 8733 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 8734 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 8735 T861 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 8736 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 8737 T861 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 8738 T861 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 8739 T861 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 8740 T861 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 8742 T861 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 8743 T861 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 8744 T861 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 8744 T861 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 8745 T861 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 8746 T861 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 8747 T861 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8748 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8749 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8750 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8751 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8762 T861 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8763 T861 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 8764 T861 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 8765 T861 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 8767 T861 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 8768 T861 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 8771 T861 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 8774 T861 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4
[junit4:junit4]   2> 8777 T861 oass.SolrIndexSearcher.<init> Opening Searcher@4c584c58 main
[junit4:junit4]   2> 8779 T861 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 8780 T861 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 8782 T861 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 8823 T861 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 8900 T938 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4c584c58 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 8910 T861 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8994 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8995 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48407/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48407_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 9283 T937 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9283 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9283 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9283 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9283 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9283 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9413 T861 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 9413 T861 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:48407/solr shardId:shard1
[junit4:junit4]   2> 9432 T861 oasc.ZkController.register We are http://127.0.0.1:48407/solr/collection1/ and leader is http://127.0.0.1:44050/solr/collection1/
[junit4:junit4]   2> 9432 T861 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48407/solr
[junit4:junit4]   2> 9433 T861 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 9434 T861 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C92 name=collection1 org.apache.solr.core.SolrCore@15751575 url=http://127.0.0.1:48407/solr/collection1 node=127.0.0.1:48407_solr
[junit4:junit4]   2> 9468 T939 C92 P48407 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 9468 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9468 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 9469 T939 C92 P48407 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 9469 T861 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 9470 T861 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9471 T861 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9490 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:44050/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 9490 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9491 T939 C92 P48407 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:48407/solr START replicas=[http://127.0.0.1:44050/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 9492 T939 C92 P48407 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 9492 T861 oasc.AbstractFullDistribZkTestBase.waitForThingsToLevelOut Wait for recoveries to finish - wait 15 for each attempt
[junit4:junit4]   2> 9492 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 9492 T861 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):15
[junit4:junit4]   2> 9493 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 9493 T894 C90 P44050 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> 9493 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9493 T939 C92 P48407 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 9786 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9787 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48407/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48407_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 9790 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9790 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9790 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9790 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9791 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 9790 T937 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 10495 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C91_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:37703/solr, state=recovering, node_name=127.0.0.1:37703_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 10832 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 10833 T925 C91 P37703 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 10834 T925 C91 P37703 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:44050/solr/collection1/. core=collection1
[junit4:junit4]   2> 10835 T925 C91 P37703 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10844 T892 C90 P44050 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 10846 T892 C90 P44050 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1
[junit4:junit4]   2> 10848 T892 C90 P44050 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 10849 T892 C90 P44050 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 5
[junit4:junit4]   2> 10851 T925 C91 P37703 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 10852 T925 C91 P37703 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 10854 T895 C90 P44050 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 10856 T925 C91 P37703 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3
[junit4:junit4]   2> 10862 T925 C91 P37703 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@78007800; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10864 T925 C91 P37703 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10866 T925 C91 P37703 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 11030 T925 C91 P37703 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@78007800; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@78007800; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 11031 T925 C91 P37703 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 11032 T925 C91 P37703 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3
[junit4:junit4]   2> 11034 T925 C91 P37703 oass.SolrIndexSearcher.<init> Opening Searcher@c5c0c5c main
[junit4:junit4]   2> 11035 T925 C91 P37703 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 11037 T924 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c5c0c5c main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 11038 T924 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty3/index
[junit4:junit4]   2> 11038 T925 C91 P37703 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 11038 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 11039 T925 C91 P37703 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11040 T925 C91 P37703 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 11295 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11295 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:37703/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:37703_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 11309 T937 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11309 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11309 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11309 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11309 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11310 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 11497 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12499 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C92_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:48407/solr, state=recovering, node_name=127.0.0.1:48407_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 12500 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 12500 T939 C92 P48407 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 12501 T939 C92 P48407 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:44050/solr/collection1/. core=collection1
[junit4:junit4]   2> 12501 T939 C92 P48407 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12507 T895 C90 P44050 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12508 T895 C90 P44050 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty1
[junit4:junit4]   2> 12508 T895 C90 P44050 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12509 T895 C90 P44050 /update {softCommit=false&commit_end_point=true&commit=true&version=2&waitSearcher=true&wt=javabin&openSearcher=false} {commit=} 0 2
[junit4:junit4]   2> 12510 T939 C92 P48407 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 12510 T939 C92 P48407 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 12512 T892 C90 P44050 REQ /replication {version=2&command=indexversion&qt=/replication&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 12513 T939 C92 P48407 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4
[junit4:junit4]   2> 12516 T939 C92 P48407 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@be90be9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12517 T939 C92 P48407 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12518 T939 C92 P48407 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 12532 T939 C92 P48407 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@be90be9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@be90be9; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 12532 T939 C92 P48407 oasc.SolrDeletionPolicy.updateCommits newest commit = 2[segments_2]
[junit4:junit4]   2> 12533 T939 C92 P48407 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4
[junit4:junit4]   2> 12536 T939 C92 P48407 oass.SolrIndexSearcher.<init> Opening Searcher@242e242e main
[junit4:junit4]   2> 12536 T939 C92 P48407 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 12547 T938 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@242e242e main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 12547 T938 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/jetty4/index
[junit4:junit4]   2> 12548 T939 C92 P48407 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 12548 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 12549 T939 C92 P48407 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12560 T939 C92 P48407 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 12814 T882 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12815 T882 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48407/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48407_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 12822 T886 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12822 T937 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12822 T881 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12822 T909 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12822 T897 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 12822 T923 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (5)
[junit4:junit4]   2> 13500 T861 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C93 name=collection1 org.apache.solr.core.SolrCore@6a726a72 url=http://127.0.0.1:37418/solr/collection1 node=127.0.0.1:37418_solr
[junit4:junit4]   2> 13506 T878 C93 P37418 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13507 T878 C93 P37418 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data
[junit4:junit4]   2> 13512 T878 C93 P37418 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.MMapDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.SyncSliceTest-1351321032415/control/data/index lockFactory=org.apache.lucene.store.NativeFSLockFactory@5ecb5ecb; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13513 T878 C93 P37418 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 13515 T878 C93 P37418 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.sol

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

 127.0.0.1:48407_solr]
[junit4:junit4]   1> scs:15 [127.0.0.1:37703_solr, 127.0.0.1:44050_solr, 127.0.0.1:37532_solr, 127.0.0.1:37418_solr, 127.0.0.1:48407_solr]
[junit4:junit4]   1> see live nodes:[127.0.0.1:37703_solr, 127.0.0.1:44050_solr, 127.0.0.1:37532_solr, 127.0.0.1:37418_solr, 127.0.0.1:48407_solr]
[junit4:junit4]   1> scs:15 [127.0.0.1:37703_solr, 127.0.0.1:44050_solr, 127.0.0.1:37532_solr, 127.0.0.1:37418_solr, 127.0.0.1:48407_solr]
[junit4:junit4]   1> see live nodes:[127.0.0.1:37703_solr, 127.0.0.1:44050_solr, 127.0.0.1:37532_solr, 127.0.0.1:37418_solr, 127.0.0.1:48407_solr]
[junit4:junit4]   1> scs:15 [127.0.0.1:37703_solr, 127.0.0.1:44050_solr, 127.0.0.1:37532_solr, 127.0.0.1:37418_solr, 127.0.0.1:48407_solr]
[junit4:junit4]   1> see live nodes:[127.0.0.1:37703_solr, 127.0.0.1:44050_solr, 127.0.0.1:37532_solr, 127.0.0.1:37418_solr, 127.0.0.1:48407_solr]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SyncSliceTest -Dtests.method=testDistribSearch -Dtests.seed=D24AB1D54ACF2E7C -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=te_IN -Dtests.timezone=SystemV/CST6 -Dtests.file.encoding=UTF-8
[junit4:junit4] FAILURE 24.5s J1 | SyncSliceTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: Shard still reported as live in zk - 0 jetty
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([D24AB1D54ACF2E7C:53AC3FCD3D904E40]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1231)
[junit4:junit4]    > 	at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitToSeeNotLive(AbstractFullDistribZkTestBase.java:1216)
[junit4:junit4]    > 	at org.apache.solr.cloud.SyncSliceTest.waitToSeeDownInClusterState(SyncSliceTest.java:259)
[junit4:junit4]    > 	at org.apache.solr.cloud.SyncSliceTest.doTest(SyncSliceTest.java:163)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:695)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:611)
[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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[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:738)
[junit4:junit4]   2> 24517 T861 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {range_facet_si=Pulsing41(freqCutoff=3 minBlockSize=31 maxBlockSize=87), range_facet_l=Pulsing41(freqCutoff=3 minBlockSize=31 maxBlockSize=87), range_facet_sl=PostingsFormat(name=Lucene41WithOrds), _version_=PostingsFormat(name=TestBloomFilteredLucene41Postings), multiDefault=Pulsing41(freqCutoff=3 minBlockSize=31 maxBlockSize=87), other_tl1=PostingsFormat(name=TestBloomFilteredLucene41Postings), rnd_b=PostingsFormat(name=Lucene41WithOrds), text=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=TestBloomFilteredLucene41Postings), a_si=PostingsFormat(name=TestBloomFilteredLucene41Postings), a_t=Pulsing41(freqCutoff=3 minBlockSize=31 maxBlockSize=87), timestamp=Pulsing41(freqCutoff=3 minBlockSize=31 maxBlockSize=87), id=PostingsFormat(name=Lucene41WithOrds)}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=te_IN, timezone=SystemV/CST6
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=1,free=17813896,total=51149824
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SolrInfoMBeanTest, RequiredFieldsTest, UpdateParamsTest, FileUtilsTest, SoftAutoCommitTest, TestReload, TestJmxIntegration, ZkNodePropsTest, TestAnalyzedSuggestions, OutputWriterTest, TestDistributedGrouping, TestFoldingMultitermQuery, RAMDirectoryFactoryTest, PeerSyncTest, BasicDistributedZk2Test, TestQuerySenderListener, DocumentBuilderTest, TestLRUCache, TestUpdate, TestSolrCoreProperties, TestArbitraryIndexDir, TestCSVLoader, TestSolrDeletionPolicy2, TestSort, LegacyHTMLStripCharFilterTest, RecoveryZkTest, TestRealTimeGet, PolyFieldTest, XmlUpdateRequestHandlerTest, MultiTermTest, OpenExchangeRatesOrgProviderTest, TestStressRecovery, TestMergePolicyConfig, TestDocSet, SuggesterTest, SyncSliceTest]
[junit4:junit4] Completed on J1 in 24.55s, 1 test, 1 failure <<< FAILURES!

[...truncated 450 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:348: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 232 suites, 961 tests, 2 failures, 7 ignored (2 assumptions)

Total time: 27 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message