Smiley,

QueryEqualityTest has a sanity check built into it to ensure no one adds a 
new built-in QParser w/o writting some query equality tests for it ... it 
seems to have caught you....


: Date: Thu, 7 Aug 2014 20:23:23 +0000 (UTC)
: From: Apache Jenkins Server <jenk...@builds.apache.org>
: Reply-To: dev@lucene.apache.org
: To: dev@lucene.apache.org
: Subject: [JENKINS] Lucene-Solr-Tests-4.x-Java7 - Build # 2049 - Still Failing
: 
: Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-Java7/2049/
: 
: 2 tests failed.
: FAILED:  junit.framework.TestSuite.org.apache.solr.search.QueryEqualityTest
: 
: Error Message:
: testParserCoverage was run w/o any other method explicitly testing qparser: 
terms
: 
: Stack Trace:
: java.lang.AssertionError: testParserCoverage was run w/o any other method 
explicitly testing qparser: terms
:       at __randomizedtesting.SeedInfo.seed([894023E3DFD774C6]:0)
:       at org.junit.Assert.fail(Assert.java:93)
:       at org.junit.Assert.assertTrue(Assert.java:43)
:       at 
org.apache.solr.search.QueryEqualityTest.afterClassParserCoverageTest(QueryEqualityTest.java:62)
:       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
:       at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
:       at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
:       at java.lang.reflect.Method.invoke(Method.java:606)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
:       at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
:       at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
:       at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
:       at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
:       at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
:       at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
:       at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
:       at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
:       at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
:       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:65)
:       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:365)
:       at java.lang.Thread.run(Thread.java:745)
: 
: 
: FAILED:  org.apache.solr.cloud.MultiThreadedOCPTest.testDistribSearch
: 
: Error Message:
: We have a failed SPLITSHARD task
: 
: Stack Trace:
: java.lang.AssertionError: We have a failed SPLITSHARD task
:       at 
__randomizedtesting.SeedInfo.seed([894023E3DFD774C6:8A6ADFBA88814FA]:0)
:       at org.junit.Assert.fail(Assert.java:93)
:       at org.junit.Assert.assertTrue(Assert.java:43)
:       at 
org.apache.solr.cloud.MultiThreadedOCPTest.testTaskExclusivity(MultiThreadedOCPTest.java:125)
:       at 
org.apache.solr.cloud.MultiThreadedOCPTest.doTest(MultiThreadedOCPTest.java:71)
:       at 
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
:       at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
:       at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
:       at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
:       at java.lang.reflect.Method.invoke(Method.java:606)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
:       at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
:       at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
:       at 
org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
:       at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
:       at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
:       at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
:       at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
:       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:365)
:       at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
:       at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
:       at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
:       at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
:       at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
:       at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
:       at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
:       at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
:       at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
:       at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
:       at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
:       at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
:       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:65)
:       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:365)
:       at java.lang.Thread.run(Thread.java:745)
: 
: 
: 
: 
: Build Log:
: [...truncated 13182 lines...]
:    [junit4] Suite: org.apache.solr.search.QueryEqualityTest
:    [junit4]   2> Creating dataDir: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001
:    [junit4]   2> 1065206 T2891 oas.SolrTestCaseJ4.buildSSLConfig Randomized 
ssl (true) and clientAuth (true)
:    [junit4]   2> 1065208 T2891 oas.SolrTestCaseJ4.initCore ####initCore
:    [junit4]   2> 1065208 T2891 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/'
:    [junit4]   2> 1065209 T2891 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
:    [junit4]   2> 1065210 T2891 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
:    [junit4]   2> 1065210 T2891 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
:    [junit4]   2> 1065344 T2891 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: LUCENE_4_10
:    [junit4]   2> 1065414 T2891 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
:    [junit4]   2> 1065415 T2891 oass.IndexSchema.readSchema Reading Solr 
Schema from schema15.xml
:    [junit4]   2> 1065425 T2891 oass.IndexSchema.readSchema [null] Schema 
name=test
:    [junit4]   2> 1065818 T2891 oass.IndexSchema.readSchema default search 
field in schema is text
:    [junit4]   2> 1065820 T2891 oass.IndexSchema.readSchema unique key field: 
id
:    [junit4]   2> 1065822 T2891 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 1065825 T2891 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 1065836 T2891 oasc.SolrResourceLoader.locateSolrHome JNDI 
not configured for solr (NoInitialContextEx)
:    [junit4]   2> 1065837 T2891 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr
:    [junit4]   2> 1065837 T2891 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/'
:    [junit4]   2> 1065878 T2891 oasc.CoreContainer.<init> New CoreContainer 
830398897
:    [junit4]   2> 1065878 T2891 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/]
:    [junit4]   2> 1065879 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 0
:    [junit4]   2> 1065879 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: null
:    [junit4]   2> 1065879 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 0
:    [junit4]   2> 1065879 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
:    [junit4]   2> 1065880 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
:    [junit4]   2> 1065880 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
:    [junit4]   2> 1065880 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
:    [junit4]   2> 1065880 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
:    [junit4]   2> 1065880 T2891 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
:    [junit4]   2> 1065883 T2891 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=false
:    [junit4]   2> 1065884 T2891 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
:    [junit4]   2> 1065884 T2891 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
:    [junit4]   2> 1065884 T2891 oasc.CoreContainer.load Host Name: 
:    [junit4]   2> 1065888 T2892 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/'
:    [junit4]   2> 1065890 T2892 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
:    [junit4]   2> 1065890 T2892 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
:    [junit4]   2> 1065890 T2892 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
:    [junit4]   2> 1065950 T2892 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: LUCENE_4_10
:    [junit4]   2> 1066019 T2892 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
:    [junit4]   2> 1066020 T2892 oass.IndexSchema.readSchema Reading Solr 
Schema from schema15.xml
:    [junit4]   2> 1066031 T2892 oass.IndexSchema.readSchema [collection1] 
Schema name=test
:    [junit4]   2> 1066425 T2892 oass.IndexSchema.readSchema default search 
field in schema is text
:    [junit4]   2> 1066428 T2892 oass.IndexSchema.readSchema unique key field: 
id
:    [junit4]   2> 1066430 T2892 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 1066435 T2892 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 1066450 T2892 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/
:    [junit4]   2> 1066450 T2892 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
:    [junit4]   2> 1066451 T2892 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/,
 
dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/
:    [junit4]   2> 1066451 T2892 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bde656e
:    [junit4]   2> 1066452 T2892 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001
:    [junit4]   2> 1066452 T2892 oasc.SolrCore.getNewIndexDir New index 
directory detected: old=null 
new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/index/
:    [junit4]   2> 1066452 T2892 oasc.SolrCore.initIndex WARN [collection1] 
Solr index directory 
'/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/index'
 doesn't exist. Creating new index...
:    [junit4]   2> 1066453 T2892 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/index
:    [junit4]   2> 1066454 T2892 oasu.RandomMergePolicy.<init> 
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: 
[TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=24, 
maxMergedSegmentMB=12.4951171875, floorSegmentMB=2.103515625, 
forceMergeDeletesPctAllowed=8.686604790539262, segmentsPerTier=14.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17091186665069513
:    [junit4]   2> 1066455 T2892 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
:    [junit4]   2>              
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@35a71706
 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1beedab1)),segFN=segments_1,generation=1}
:    [junit4]   2> 1066455 T2892 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
:    [junit4]   2> 1066455 T2892 oasc.SolrCore.initWriters created xml: 
solr.XMLResponseWriter
:    [junit4]   2> 1066459 T2892 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "dedupe"
:    [junit4]   2> 1066460 T2892 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe"
:    [junit4]   2> 1066460 T2892 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "dedupe-allfields"
:    [junit4]   2> 1066460 T2892 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
:    [junit4]   2> 1066461 T2892 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "stored_sig"
:    [junit4]   2> 1066461 T2892 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"stored_sig"
:    [junit4]   2> 1066461 T2892 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "uniq-fields"
:    [junit4]   2> 1066461 T2892 oasup.UniqFieldsUpdateProcessorFactory.init 
WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is 
deprecated, please use 'fieldName' (or another 
FieldMutatingUpdateProcessorFactory selector option) instead
:    [junit4]   2> 1066462 T2892 oasup.UniqFieldsUpdateProcessorFactory.init 
Replacing 'fields' init param with (individual) 'fieldName' params
:    [junit4]   2> 1066462 T2892 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"uniq-fields"
:    [junit4]   2> 1066462 T2892 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
:    [junit4]   2> 1066463 T2892 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
:    [junit4]   2> 1066463 T2892 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
:    [junit4]   2> 1066463 T2892 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
:    [junit4]   2> 1066464 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /update: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 1066464 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 1066465 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 1066465 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 1066465 T2892 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
:    [junit4]   2> 1066466 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
:    [junit4]   2> 1066466 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
:    [junit4]   2> 1066467 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
:    [junit4]   2> 1066467 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created dismax: solr.SearchHandler
:    [junit4]   2> 1066468 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
:    [junit4]   2> 1066468 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
:    [junit4]   2> 1066469 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
:    [junit4]   2> 1066469 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
:    [junit4]   2> 1066469 T2892 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
:    [junit4]   2> 1066470 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
:    [junit4]   2> 1066470 T2892 oasc.RequestHandlers.initHandlersFromConfig 
WARN Multiple requestHandler registered to the same name: /update ignoring: 
org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 1066470 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
:    [junit4]   2> 1066471 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /terms: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066471 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066472 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066472 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066473 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckWithWordbreak_Direct: 
org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066473 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066474 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created mltrh: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066474 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created tvrh: org.apache.solr.handler.component.SearchHandler
:    [junit4]   2> 1066475 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /mlt: solr.MoreLikeThisHandler
:    [junit4]   2> 1066475 T2892 oasc.RequestHandlers.initHandlersFromConfig 
created /debug/dump: solr.DumpRequestHandler
:    [junit4]   2> 1066494 T2892 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 1066496 T2892 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 1066498 T2892 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 1066500 T2892 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 1066502 T2892 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 1066504 T2892 oasc.SolrCore.initDeprecatedSupport WARN 
solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config 
to use the ShowFileRequestHandler.
:    [junit4]   2> 1066506 T2892 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
:    [junit4]   2> 1066507 T2892 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
:    [junit4]   2> 1066507 T2892 oasu.RandomMergePolicy.<init> 
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: 
[TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=36, 
maxMergedSegmentMB=38.9736328125, floorSegmentMB=1.5791015625, 
forceMergeDeletesPctAllowed=25.466902262123792, segmentsPerTier=35.0, 
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
:    [junit4]   2> 1066508 T2892 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
:    [junit4]   2>              
commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@35a71706
 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1beedab1)),segFN=segments_1,generation=1}
:    [junit4]   2> 1066508 T2892 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
:    [junit4]   2> 1066509 T2892 oass.SolrIndexSearcher.<init> Opening 
Searcher@32ea2a58[collection1] main
:    [junit4]   2> 1066509 T2892 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
:    [junit4]   2> 1066509 T2892 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using InMemoryStorage
:    [junit4]   2> 1066510 T2892 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
:    [junit4]   2> 1066510 T2892 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using InMemoryStorage
:    [junit4]   2> 1066510 T2892 oasr.RestManager.init Initializing 0 
registered ManagedResources
:    [junit4]   2> 1066510 T2892 oashc.SpellCheckComponent.inform Initializing 
spell checkers
:    [junit4]   2> 1066531 T2892 oass.DirectSolrSpellChecker.init init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
:    [junit4]   2> 1066579 T2893 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@32ea2a58[collection1] 
main{StandardDirectoryReader(segments_1:1:nrt)}
:    [junit4]   2> 1066582 T2892 oasc.CoreContainer.registerCore registering 
core: collection1
:    [junit4]   2> 1066585 T2891 oas.SolrTestCaseJ4.initCore ####initCore end
:    [junit4]   2> 1066589 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncGeodist
:    [junit4]   2> 1066626 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncGeodist
:    [junit4]   2> 1066632 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryLucene
:    [junit4]   2> 1066635 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryLucene
:    [junit4]   2> 1066640 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncOrd
:    [junit4]   2> 1066640 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncOrd
:    [junit4]   2> 1066645 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncMod
:    [junit4]   2> 1066646 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMod
:    [junit4]   2> 1066651 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncCscore
:    [junit4]   2> 1066658 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncCscore
:    [junit4]   2> 1066663 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncDist
:    [junit4]   2> 1066664 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncDist
:    [junit4]   2> 1066668 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncExists
:    [junit4]   2> 1066679 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncExists
:    [junit4]   2> 1066684 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncMap
:    [junit4]   2> 1066693 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMap
:    [junit4]   2> 1066698 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncRord
:    [junit4]   2> 1066699 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncRord
:    [junit4]   2> 1066704 T2891 oas.SolrTestCaseJ4.setUp ###Starting testField
:    [junit4]   2> 1066704 T2891 oas.SolrTestCaseJ4.tearDown ###Ending testField
:    [junit4]   2> 1066709 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryComplexPhrase
:    [junit4]   2> 1066860 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryComplexPhrase
:    [junit4]   2> 1066865 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQuerySimple
:    [junit4]   2> 1066887 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQuerySimple
:    [junit4]   2> 1066892 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncGeohash
:    [junit4]   2> 1066893 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncGeohash
:    [junit4]   2> 1066898 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryDismax
:    [junit4]   2> 1066906 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryDismax
:    [junit4]   2> 1066911 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryGeofilt
:    [junit4]   2> 1066915 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryGeofilt
:    [junit4]   2> 1066921 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryPrefix
:    [junit4]   2> 1066921 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryPrefix
:    [junit4]   2> 1066936 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testTestFuncs
:    [junit4]   2> 1066941 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testTestFuncs
:    [junit4]   2> 1066946 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncSqedist
:    [junit4]   2> 1066947 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncSqedist
:    [junit4]   2> 1066952 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncSum
:    [junit4]   2> 1066954 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncSum
:    [junit4]   2> 1066959 T2891 oas.SolrTestCaseJ4.setUp ###Starting testFuncIf
:    [junit4]   2> 1066966 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncIf
:    [junit4]   2> 1066971 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryBoost
:    [junit4]   2> 1066980 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryBoost
:    [junit4]   2> 1066985 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncDef
:    [junit4]   2> 1067003 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncDef
:    [junit4]   2> 1067008 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncNumdocs
:    [junit4]   2> 1067017 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncNumdocs
:    [junit4]   2> 1067022 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testDateMathParsingEquality
:    [junit4]   2> 1067025 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testDateMathParsingEquality
:    [junit4]   2> 1067029 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncMin
:    [junit4]   2> 1067031 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMin
:    [junit4]   2> 1067036 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryTerm
:    [junit4]   2> 1067036 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryTerm
:    [junit4]   2> 1067041 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncDiv
:    [junit4]   2> 1067042 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncDiv
:    [junit4]   2> 1067047 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncBoost
:    [junit4]   2> 1067048 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncBoost
:    [junit4]   2> 1067053 T2891 oas.SolrTestCaseJ4.setUp ###Starting testFuncMs
:    [junit4]   2> 1067070 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMs
:    [junit4]   2> 1067075 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncNorm
:    [junit4]   2> 1067097 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncNorm
:    [junit4]   2> 1067103 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncLiteral
:    [junit4]   2> 1067103 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncLiteral
:    [junit4]   2> 1067108 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncLinear
:    [junit4]   2> 1067117 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncLinear
:    [junit4]   2> 1067122 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncTestfunc
:    [junit4]   2> 1067123 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncTestfunc
:    [junit4]   2> 1067128 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryNested
:    [junit4]   2> 1067129 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryNested
:    [junit4]   2> 1067135 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncMaxdoc
:    [junit4]   2> 1067135 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMaxdoc
:    [junit4]   2> 1067140 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testBlockJoin
:    [junit4]   2> 1067164 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testBlockJoin
:    [junit4]   2> 1067169 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryFrange
:    [junit4]   2> 1067170 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryFrange
:    [junit4]   2> 1067175 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncNot
:    [junit4]   2> 1067177 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncNot
:    [junit4]   2> 1067181 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncDoubleValueBools
:    [junit4]   2> 1067193 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncDoubleValueBools
:    [junit4]   2> 1067198 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncRecip
:    [junit4]   2> 1067199 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncRecip
:    [junit4]   2> 1067203 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryRaw
:    [junit4]   2> 1067204 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryRaw
:    [junit4]   2> 1067209 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryJoin
:    [junit4]   2> 1067222 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryJoin
:    [junit4]   2> 1067226 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryLucenePlusSort
:    [junit4]   2> 1067229 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryLucenePlusSort
:    [junit4]   2> 1067234 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncTop
:    [junit4]   2> 1067235 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncTop
:    [junit4]   2> 1067239 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncSingleValueMathFuncs
:    [junit4]   2> 1067249 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncSingleValueMathFuncs
:    [junit4]   2> 1067254 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testParserCoverage
:    [junit4]   2> 1067254 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testParserCoverage
:    [junit4]   2> 1067259 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncVector
:    [junit4]   2> 1067261 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncVector
:    [junit4]   2> 1067265 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncGhhsin
:    [junit4]   2> 1067266 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncGhhsin
:    [junit4]   2> 1067271 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryBbox
:    [junit4]   2> 1067275 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryBbox
:    [junit4]   2> 1067279 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncHsin
:    [junit4]   2> 1067280 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncHsin
:    [junit4]   2> 1067285 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncTerms
:    [junit4]   2> 1067316 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncTerms
:    [junit4]   2> 1067321 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testReRankQuery
:    [junit4]   2> 1067341 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testReRankQuery
:    [junit4]   2> 1067345 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryMaxScore
:    [junit4]   2> 1067350 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryMaxScore
:    [junit4]   2> 1067355 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryCollapse
:    [junit4]   2> 1067373 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryCollapse
:    [junit4]   2> 1067378 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncCurrency
:    [junit4]   2> 1067385 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncCurrency
:    [junit4]   2> 1067389 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncSub
:    [junit4]   2> 1067391 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncSub
:    [junit4]   2> 1067395 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncDoubleValueMathFuncs
:    [junit4]   2> 1067403 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncDoubleValueMathFuncs
:    [junit4]   2> 1067408 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncProduct
:    [junit4]   2> 1067409 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncProduct
:    [junit4]   2> 1067414 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQuerySurround
:    [junit4]   2> 1067669 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQuerySurround
:    [junit4]   2> 1067674 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncField
:    [junit4]   2> 1067675 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncField
:    [junit4]   2> 1067680 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncJoindf
:    [junit4]   2> 1067680 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncJoindf
:    [junit4]   2> 1067685 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncMax
:    [junit4]   2> 1067686 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMax
:    [junit4]   2> 1067691 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncQuery
:    [junit4]   2> 1067692 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncQuery
:    [junit4]   2> 1067697 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncScale
:    [junit4]   2> 1067706 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncScale
:    [junit4]   2> 1067711 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncMathConsts
:    [junit4]   2> 1067712 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncMathConsts
:    [junit4]   2> 1067716 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncBools
:    [junit4]   2> 1067717 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncBools
:    [junit4]   2> 1067722 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncStrdist
:    [junit4]   2> 1067738 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncStrdist
:    [junit4]   2> 1067743 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQuerySwitch
:    [junit4]   2> 1067746 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQuerySwitch
:    [junit4]   2> 1067751 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testQueryFunc
:    [junit4]   2> 1067753 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testQueryFunc
:    [junit4]   2> 1067757 T2891 oas.SolrTestCaseJ4.setUp ###Starting 
testFuncSttf
:    [junit4]   2> 1067758 T2891 oas.SolrTestCaseJ4.tearDown ###Ending 
testFuncSttf
:    [junit4]   2> 1067760 T2891 oas.SolrTestCaseJ4.deleteCore ###deleteCore
:    [junit4]   2> 1067760 T2891 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=830398897
:    [junit4]   2> 1067761 T2891 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@1b4d383e
:    [junit4]   2> 1067775 T2891 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,transaction_logs_total_size=0,transaction_logs_total_number=0}
:    [junit4]   2> 1067775 T2891 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
:    [junit4]   2> 1067775 T2891 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
:    [junit4]   2> 1067775 T2891 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
:    [junit4]   2> 1067777 T2891 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
:    [junit4]   2> 1067777 T2891 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
:    [junit4]   2> 1067777 T2891 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001;done=false>>]
:    [junit4]   2> 1067778 T2891 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001
:    [junit4]   2> 1067778 T2891 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/index;done=false>>]
:    [junit4]   2> 1067778 T2891 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.search.QueryEqualityTest-894023E3DFD774C6-001/init-core-data-001/index
:    [junit4]   2> NOTE: test params are: codec=Lucene49: {}, docValues:{}, 
sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=en_PH, 
timezone=America/Port-au-Prince
:    [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 
1.7.0_60 (64-bit)/cpus=16,threads=1,free=392664904,total=459276288
:    [junit4]   2> NOTE: All tests run in this JVM: [DirectUpdateHandlerTest, 
CoreAdminCreateDiscoverTest, TestQuerySenderListener, MinimalSchemaTest, 
TestSolrDeletionPolicy1, CollectionsAPIDistributedZkTest, 
TestLuceneMatchVersion, TestQuerySenderNoQuery, TestManagedStopFilterFactory, 
TestDynamicFieldCollectionResource, TestSearchPerf, SortByFunctionTest, 
TestStressRecovery, TestCustomSort, SuggesterFSTTest, TestFaceting, 
TestStressLucene, TestChildDocTransformer, DirectSolrConnectionTest, 
EnumFieldTest, SolrIndexSplitterTest, IndexBasedSpellCheckerTest, TestDocSet, 
DocValuesMultiTest, CustomCollectionTest, LegacyHTMLStripCharFilterTest, 
TestSchemaVersionResource, ConnectionManagerTest, TestMergePolicyConfig, 
TestPseudoReturnFields, TestFieldSortValues, OverseerRolesTest, DateFieldTest, 
TermVectorComponentDistributedTest, TestIBSimilarityFactory, 
TestSolrXmlPersistence, FieldAnalysisRequestHandlerTest, 
DistribCursorPagingTest, SimpleFacetsTest, DistanceFunctionTest, 
TestSolrXMLSeria
 lizer, TestExtendedDismaxParser, HdfsBasicDistributedZk2Test, 
DocumentBuilderTest, FileUtilsTest, UpdateParamsTest, 
DocumentAnalysisRequestHandlerTest, UnloadDistributedZkTest, CircularListTest, 
ZkNodePropsTest, ScriptEngineTest, BasicZkTest, 
DistributedQueryComponentOptimizationTest, TestCSVResponseWriter, 
ParsingFieldUpdateProcessorsTest, HdfsRecoveryZkTest, OverseerStatusTest, 
StatsComponentTest, TestWriterPerf, ClusterStateUpdateTest, 
TestUniqueKeyFieldResource, TestNumberUtils, QueryEqualityTest]
:    [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=QueryEqualityTest 
-Dtests.seed=894023E3DFD774C6 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=en_PH -Dtests.timezone=America/Port-au-Prince 
-Dtests.file.encoding=ISO-8859-1
:    [junit4] ERROR   0.00s J0 | QueryEqualityTest (suite) <<<
:    [junit4]    > Throwable #1: java.lang.AssertionError: testParserCoverage 
was run w/o any other method explicitly testing qparser: terms
:    [junit4]    >      at 
__randomizedtesting.SeedInfo.seed([894023E3DFD774C6]:0)
:    [junit4]    >      at 
org.apache.solr.search.QueryEqualityTest.afterClassParserCoverageTest(QueryEqualityTest.java:62)
:    [junit4]    >      at java.lang.Thread.run(Thread.java:745)
:    [junit4] Completed on J0 in 2.65s, 70 tests, 1 failure <<< FAILURES!
: 
: [...truncated 383 lines...]
:    [junit4] Suite: org.apache.solr.cloud.MultiThreadedOCPTest
:    [junit4]   2> Creating dataDir: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/init-core-data-001
:    [junit4]   2> 3591190 T5910 oas.SolrTestCaseJ4.buildSSLConfig Randomized 
ssl (false) and clientAuth (false)
:    [junit4]   2> 3591191 T5910 
oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system 
property: /
:    [junit4]   2> 3591195 T5910 oas.SolrTestCaseJ4.setUp ###Starting 
testDistribSearch
:    [junit4]   2> 3591196 T5910 oasc.ZkTestServer.run STARTING ZK TEST SERVER
:    [junit4]   1> client port:0.0.0.0/0.0.0.0:0
:    [junit4]   2> 3591197 T5911 oasc.ZkTestServer$ZKServerMain.runFromConfig 
Starting server
:    [junit4]   2> 3591297 T5910 oasc.ZkTestServer.run start zk server on 
port:53984
:    [junit4]   2> 3591298 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3591301 T5917 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@4230cb67 
name:ZooKeeperConnection Watcher:127.0.0.1:53984 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3591301 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3591301 T5910 oascc.SolrZkClient.makePath makePath: /solr
:    [junit4]   2> 3591305 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3591305 T5919 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@719a6a80 
name:ZooKeeperConnection Watcher:127.0.0.1:53984/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3591306 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3591306 T5910 oascc.SolrZkClient.makePath makePath: 
/collections/collection1
:    [junit4]   2> 3591308 T5910 oascc.SolrZkClient.makePath makePath: 
/collections/collection1/shards
:    [junit4]   2> 3591309 T5910 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection
:    [junit4]   2> 3591311 T5910 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection/shards
:    [junit4]   2> 3591313 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
:    [junit4]   2> 3591313 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/solrconfig.xml
:    [junit4]   2> 3591316 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
:    [junit4]   2> 3591317 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/schema.xml
:    [junit4]   2> 3591419 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
:    [junit4]   2> 3591419 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
:    [junit4]   2> 3591421 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
:    [junit4]   2> 3591422 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/stopwords.txt
:    [junit4]   2> 3591424 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
:    [junit4]   2> 3591424 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/protwords.txt
:    [junit4]   2> 3591426 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
:    [junit4]   2> 3591427 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/currency.xml
:    [junit4]   2> 3591429 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
:    [junit4]   2> 3591429 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/enumsConfig.xml
:    [junit4]   2> 3591431 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
:    [junit4]   2> 3591432 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/open-exchange-rates.json
:    [junit4]   2> 3591434 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
:    [junit4]   2> 3591434 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/mapping-ISOLatin1Accent.txt
:    [junit4]   2> 3591436 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
:    [junit4]   2> 3591437 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/old_synonyms.txt
:    [junit4]   2> 3591438 T5910 oasc.AbstractZkTestCase.putConfig put 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
:    [junit4]   2> 3591439 T5910 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/synonyms.txt
:    [junit4]   2> 3592574 T5910 oejs.Server.doStart jetty-8.1.10.v20130312
:    [junit4]   2> 3592578 T5910 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:44580
:    [junit4]   2> 3592579 T5910 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
:    [junit4]   2> 3592579 T5910 oasc.SolrResourceLoader.locateSolrHome JNDI 
not configured for solr (NoInitialContextEx)
:    [junit4]   2> 3592579 T5910 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002
:    [junit4]   2> 3592579 T5910 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/'
:    [junit4]   2> 3592609 T5910 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/solr.xml
:    [junit4]   2> 3592662 T5910 oasc.CoreContainer.<init> New CoreContainer 
1199701395
:    [junit4]   2> 3592662 T5910 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/]
:    [junit4]   2> 3592663 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
:    [junit4]   2> 3592664 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
:    [junit4]   2> 3592664 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
:    [junit4]   2> 3592664 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
:    [junit4]   2> 3592664 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
:    [junit4]   2> 3592665 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
:    [junit4]   2> 3592665 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
:    [junit4]   2> 3592665 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
:    [junit4]   2> 3592665 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
:    [junit4]   2> 3592666 T5910 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
:    [junit4]   2> 3592666 T5910 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
:    [junit4]   2> 3592666 T5910 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
:    [junit4]   2> 3592667 T5910 oasc.CoreContainer.load Host Name: 127.0.0.1
:    [junit4]   2> 3592667 T5910 oasc.ZkContainer.initZooKeeper Zookeeper 
client=127.0.0.1:53984/solr
:    [junit4]   2> 3592667 T5910 oasc.ZkController.checkChrootPath zkHost 
includes chroot
:    [junit4]   2> 3592668 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3592669 T5930 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@272ee292 
name:ZooKeeperConnection Watcher:127.0.0.1:53984 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3592670 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3592672 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3592672 T5932 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@14120bea 
name:ZooKeeperConnection Watcher:127.0.0.1:53984/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3592673 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3592674 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer/queue
:    [junit4]   2> 3592676 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer/collection-queue-work
:    [junit4]   2> 3592678 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer/collection-map-running
:    [junit4]   2> 3592680 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer/collection-map-completed
:    [junit4]   2> 3592681 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer/collection-map-failure
:    [junit4]   2> 3592683 T5910 oascc.SolrZkClient.makePath makePath: 
/live_nodes
:    [junit4]   2> 3592684 T5910 oasc.ZkController.createEphemeralLiveNode 
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44580_
:    [junit4]   2> 3592685 T5910 oascc.SolrZkClient.makePath makePath: 
/live_nodes/127.0.0.1:44580_
:    [junit4]   2> 3592687 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer_elect
:    [junit4]   2> 3592688 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer_elect/election
:    [junit4]   2> 3592690 T5910 oasc.OverseerElectionContext.runLeaderProcess 
I am going to be the leader 127.0.0.1:44580_
:    [junit4]   2> 3592691 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer_elect/leader
:    [junit4]   2> 3592692 T5910 oasc.Overseer.start Overseer 
(id=92238083035824131-127.0.0.1:44580_-n_0000000000) starting
:    [junit4]   2> 3592694 T5910 oascc.SolrZkClient.makePath makePath: 
/overseer/queue-work
:    [junit4]   2> 3592699 T5934 oasc.OverseerCollectionProcessor.run Process 
current queue of collection creations
:    [junit4]   2> 3592700 T5910 oascc.SolrZkClient.makePath makePath: 
/clusterstate.json
:    [junit4]   2> 3592701 T5910 oascc.SolrZkClient.makePath makePath: 
/aliases.json
:    [junit4]   2> 3592703 T5910 
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state 
from ZooKeeper... 
:    [junit4]   2> 3592704 T5933 oasc.Overseer$ClusterStateUpdater.run Starting 
to work on the main queue
:    [junit4]   2> 3592709 T5935 oasc.ZkController.publish publishing 
core=collection1 state=down collection=control_collection
:    [junit4]   2> 3592709 T5935 oasc.ZkController.publish numShards not found 
on descriptor - reading it from system property
:    [junit4]   2> 3592710 T5935 oasc.ZkController.waitForCoreNodeName look for 
our core node name
:    [junit4]   2> 3592710 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3592711 T5933 oascc.ZkStateReader.updateClusterState 
Updating cloud state from ZooKeeper... 
:    [junit4]   2> 3592712 T5933 oasc.Overseer$ClusterStateUpdater.updateState 
Update state numShards=1 message={
:    [junit4]   2>        "operation":"state",
:    [junit4]   2>        "state":"down",
:    [junit4]   2>        "base_url":"http://127.0.0.1:44580";,
:    [junit4]   2>        "core":"collection1",
:    [junit4]   2>        "roles":null,
:    [junit4]   2>        "node_name":"127.0.0.1:44580_",
:    [junit4]   2>        "shard":null,
:    [junit4]   2>        "collection":"control_collection",
:    [junit4]   2>        "numShards":"1",
:    [junit4]   2>        "core_node_name":null}
:    [junit4]   2> 3592712 T5933 
oasc.Overseer$ClusterStateUpdater.createCollection Create collection 
control_collection with shards [shard1]
:    [junit4]   2> 3592712 T5933 oasc.Overseer$ClusterStateUpdater.updateState 
Assigning new node to shard shard=shard1
:    [junit4]   2> 3592714 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3592714 T5936 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3593710 T5935 oasc.ZkController.waitForShardId waiting to 
find shard id in clusterstate for collection1
:    [junit4]   2> 3593711 T5935 oasc.ZkController.createCollectionZkNode Check 
for collection zkNode:control_collection
:    [junit4]   2> 3593712 T5935 oasc.ZkController.createCollectionZkNode 
Collection zkNode exists
:    [junit4]   2> 3593712 T5935 oascc.ZkStateReader.readConfigName Load 
collection config from:/collections/control_collection
:    [junit4]   2> 3593713 T5935 oascc.ZkStateReader.readConfigName 
path=/collections/control_collection configName=conf1 specified config exists 
in ZooKeeper
:    [junit4]   2> 3593713 T5935 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/collection1/'
:    [junit4]   2> 3593714 T5935 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/collection1/lib/classes/'
 to classloader
:    [junit4]   2> 3593715 T5935 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/collection1/lib/.svn/'
 to classloader
:    [junit4]   2> 3593715 T5935 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/collection1/lib/README'
 to classloader
:    [junit4]   2> 3593779 T5935 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: LUCENE_4_10
:    [junit4]   2> 3593827 T5935 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
:    [junit4]   2> 3593928 T5935 oass.IndexSchema.readSchema Reading Solr 
Schema from schema.xml
:    [junit4]   2> 3593939 T5935 oass.IndexSchema.readSchema [collection1] 
Schema name=test
:    [junit4]   2> 3594409 T5935 oass.ByteField.init WARN ByteField is 
deprecated and will be removed in 5.0. You should use TrieIntField instead.
:    [junit4]   2> 3594410 T5935 oass.ShortField.init WARN ShortField is 
deprecated and will be removed in 5.0. You should use TrieIntField instead.
:    [junit4]   2> 3594411 T5935 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
:    [junit4]   2> 3594423 T5935 oass.IndexSchema.readSchema default search 
field in schema is text
:    [junit4]   2> 3594427 T5935 oass.IndexSchema.readSchema unique key field: 
id
:    [junit4]   2> 3594438 T5935 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 3594442 T5935 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 3594446 T5935 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
:    [junit4]   2> 3594447 T5935 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
:    [junit4]   2> 3594447 T5935 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
:    [junit4]   2> 3594448 T5935 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
:    [junit4]   2> 3594449 T5935 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
:    [junit4]   2> 3594449 T5935 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
:    [junit4]   2> 3594449 T5935 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from collection control_collection
:    [junit4]   2> 3594449 T5935 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
:    [junit4]   2> 3594450 T5935 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-002/collection1/,
 
dataDir=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-001/control/data/
:    [junit4]   2> 3594450 T5935 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bde656e
:    [junit4]   2> 3594451 T5935 oasc.CachingDirectoryFactory.get return new 
directory for 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-001/control/data
:    [junit4]   2> 3594451 T5935 oasc.SolrCore.getNewIndexDir New index 
directory detected: old=null 
new=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-001/control/data/index/
:    [junit4]   2> 3594451 T5935 oasc.SolrCore.initIndex WARN [collection1] 
Solr index directory 
'./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-001/control/data/index'
 doesn't exist. Creating new index...
:    [junit4]   2> 3594451 T5935 oasc.CachingDirectoryFactory.get return new 
directory for 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-001/control/data/index
:    [junit4]   2> 3594452 T5935 oasu.RandomMergePolicy.<init> 
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: 
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, 
maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
:    [junit4]   2> 3594453 T5935 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
:    [junit4]   2>              
commit{dir=MockDirectoryWrapper(RAMDirectory@5de6da92 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78db4089),segFN=segments_1,generation=1}
:    [junit4]   2> 3594453 T5935 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
:    [junit4]   2> 3594457 T5935 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "nodistrib"
:    [junit4]   2> 3594457 T5935 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "dedupe"
:    [junit4]   2> 3594457 T5935 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe"
:    [junit4]   2> 3594458 T5935 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "stored_sig"
:    [junit4]   2> 3594458 T5935 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"stored_sig"
:    [junit4]   2> 3594458 T5935 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
:    [junit4]   2> 3594459 T5935 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
:    [junit4]   2> 3594459 T5935 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
:    [junit4]   2> 3594459 T5935 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
:    [junit4]   2> 3594460 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /update: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3594460 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3594461 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3594461 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3594462 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
:    [junit4]   2> 3594462 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
:    [junit4]   2> 3594462 T5935 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
:    [junit4]   2> 3594463 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
:    [junit4]   2> 3594463 T5935 oasc.RequestHandlers.initHandlersFromConfig 
WARN Multiple requestHandler registered to the same name: /update ignoring: 
org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3594463 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
:    [junit4]   2> 3594464 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/: org.apache.solr.handler.admin.AdminHandlers
:    [junit4]   2> 3594464 T5935 oasc.RequestHandlers.initHandlersFromConfig 
created /admin/file: solr.admin.ShowFileRequestHandler
:    [junit4]   2> 3594483 T5935 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 3594485 T5935 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 3594487 T5935 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 3594489 T5935 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 3594491 T5935 oashl.XMLLoader.init 
xsltCacheLifetimeSeconds=60
:    [junit4]   2> 3594497 T5935 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
:    [junit4]   2> 3594497 T5935 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
:    [junit4]   2> 3594498 T5935 oasu.RandomMergePolicy.<init> 
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: 
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=43, 
maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.33125372141014287]
:    [junit4]   2> 3594499 T5935 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
:    [junit4]   2>              
commit{dir=MockDirectoryWrapper(RAMDirectory@5de6da92 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@78db4089),segFN=segments_1,generation=1}
:    [junit4]   2> 3594499 T5935 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
:    [junit4]   2> 3594499 T5935 oass.SolrIndexSearcher.<init> Opening 
Searcher@7900b887[collection1] main
:    [junit4]   2> 3594500 T5935 oascc.ZkStateReader.readConfigName Load 
collection config from:/collections/control_collection
:    [junit4]   2> 3594500 T5935 oascc.ZkStateReader.readConfigName 
path=/collections/control_collection configName=conf1 specified config exists 
in ZooKeeper
:    [junit4]   2> 3594501 T5935 oasr.ManagedResourceStorage.newStorageIO 
Setting up ZooKeeper-based storage for the RestManager with znodeBase: 
/configs/conf1
:    [junit4]   2> 3594501 T5935 
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured 
ZooKeeperStorageIO with znodeBase: /configs/conf1
:    [junit4]   2> 3594501 T5935 oasr.RestManager.init Initializing RestManager 
with initArgs: {}
:    [junit4]   2> 3594502 T5935 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
:    [junit4]   2> 3594502 T5935 
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found 
for znode /configs/conf1/_rest_managed.json
:    [junit4]   2> 3594502 T5935 oasr.ManagedResourceStorage.load Loaded null 
at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
:    [junit4]   2> 3594503 T5935 oascc.SolrZkClient.makePath makePath: 
/configs/conf1/_rest_managed.json
:    [junit4]   2> 3594505 T5935 
oasr.ManagedResourceStorage$ZooKeeperStorageIO$1.close Wrote 38 bytes to new 
znode /configs/conf1/_rest_managed.json
:    [junit4]   2> 3594505 T5935 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf1
:    [junit4]   2> 3594505 T5935 oasr.RestManager.init Initializing 0 
registered ManagedResources
:    [junit4]   2> 3594508 T5937 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@7900b887[collection1] 
main{StandardDirectoryReader(segments_1:1:nrt)}
:    [junit4]   2> 3594511 T5935 oasc.CoreContainer.registerCore registering 
core: collection1
:    [junit4]   2> 3594512 T5940 oasc.ZkController.register Register replica - 
core:collection1 address:http://127.0.0.1:44580 collection:control_collection 
shard:shard1
:    [junit4]   2> 3594513 T5910 oass.SolrDispatchFilter.init 
user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0
:    [junit4]   2> 3594513 T5910 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
:    [junit4]   2> 3594513 T5940 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection/leader_elect/shard1/election
:    [junit4]   2> 3594514 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3594516 T5942 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@46bab9b4 
name:ZooKeeperConnection Watcher:127.0.0.1:53984/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3594516 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3594517 T5910 
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state 
from ZooKeeper... 
:    [junit4]   2> 3594518 T5940 
oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for 
shard shard1
:    [junit4]   2> 3594519 T5910 oasc.ChaosMonkey.monkeyLog monkey: init - 
expire sessions:false cause connection loss:false
:    [junit4]   2> 3594520 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594520 T5940 
oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found 
to continue.
:    [junit4]   2> 3594520 T5940 
oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try 
and sync
:    [junit4]   2> ASYNC  NEW_CORE C1128 name=collection1 
org.apache.solr.core.SolrCore@24159ed7 url=http://127.0.0.1:44580/collection1 
node=127.0.0.1:44580_ C1128_STATE=coll:control_collection core:collection1 
props:{state=down, base_url=http://127.0.0.1:44580, core=collection1, 
node_name=127.0.0.1:44580_}
:    [junit4]   2> 3594520 T5940 C1128 P44580 oasc.SyncStrategy.sync Sync 
replicas to http://127.0.0.1:44580/collection1/
:    [junit4]   2> 3594521 T5940 C1128 P44580 oasc.SyncStrategy.syncReplicas 
Sync Success - now sync replicas to me
:    [junit4]   2> 3594521 T5933 oascc.ZkStateReader.updateClusterState 
Updating cloud state from ZooKeeper... 
:    [junit4]   2> 3594521 T5940 C1128 P44580 oasc.SyncStrategy.syncToMe 
http://127.0.0.1:44580/collection1/ has no replicas
:    [junit4]   2> 3594521 T5940 
oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: 
http://127.0.0.1:44580/collection1/ shard1
:    [junit4]   2> 3594521 T5940 oascc.SolrZkClient.makePath makePath: 
/collections/control_collection/leaders/shard1
:    [junit4]   2> 3594523 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594523 T5936 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3594523 T5943 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3594527 T5933 oascc.ZkStateReader.updateClusterState 
Updating cloud state from ZooKeeper... 
:    [junit4]   2> 3594528 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594630 T5936 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3594630 T5943 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3594679 T5940 oasc.ZkController.register We are 
http://127.0.0.1:44580/collection1/ and leader is 
http://127.0.0.1:44580/collection1/
:    [junit4]   2> 3594680 T5940 oasc.ZkController.register No LogReplay needed 
for core=collection1 baseURL=http://127.0.0.1:44580
:    [junit4]   2> 3594680 T5940 oasc.ZkController.checkRecovery I am the 
leader, no recovery necessary
:    [junit4]   2> 3594680 T5940 oasc.ZkController.publish publishing 
core=collection1 state=active collection=control_collection
:    [junit4]   2> 3594680 T5940 oasc.ZkController.publish numShards not found 
on descriptor - reading it from system property
:    [junit4]   2> 3594681 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594682 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594682 T5940 oascc.ZkStateReader.updateClusterState 
Updating cloud state from ZooKeeper... 
:    [junit4]   2> 3594682 T5944 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594683 T5933 oascc.ZkStateReader.updateClusterState 
Updating cloud state from ZooKeeper... 
:    [junit4]   2> 3594683 T5933 oasc.Overseer$ClusterStateUpdater.updateState 
Update state numShards=2 message={
:    [junit4]   2>        "operation":"state",
:    [junit4]   2>        "state":"active",
:    [junit4]   2>        "base_url":"http://127.0.0.1:44580";,
:    [junit4]   2>        "core":"collection1",
:    [junit4]   2>        "roles":null,
:    [junit4]   2>        "node_name":"127.0.0.1:44580_",
:    [junit4]   2>        "shard":"shard1",
:    [junit4]   2>        "collection":"control_collection",
:    [junit4]   2>        "numShards":"2",
:    [junit4]   2>        "core_node_name":"core_node1"}
:    [junit4]   2> 3594685 T5944 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3594786 T5944 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3594786 T5943 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
:    [junit4]   2> 3595631 T5910 
oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
:    [junit4]   2> 3595632 T5910 oejs.Server.doStart jetty-8.1.10.v20130312
:    [junit4]   2> 3595635 T5910 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:27736
:    [junit4]   2> 3595635 T5910 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
:    [junit4]   2> 3595636 T5910 oasc.SolrResourceLoader.locateSolrHome JNDI 
not configured for solr (NoInitialContextEx)
:    [junit4]   2> 3595636 T5910 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003
:    [junit4]   2> 3595636 T5910 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/'
:    [junit4]   2> 3595665 T5910 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/solr.xml
:    [junit4]   2> 3595719 T5910 oasc.CoreContainer.<init> New CoreContainer 
770910585
:    [junit4]   2> 3595719 T5910 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/]
:    [junit4]   2> 3595720 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
:    [junit4]   2> 3595720 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
:    [junit4]   2> 3595721 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
:    [junit4]   2> 3595721 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
:    [junit4]   2> 3595721 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
:    [junit4]   2> 3595721 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
:    [junit4]   2> 3595722 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
:    [junit4]   2> 3595722 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
:    [junit4]   2> 3595722 T5910 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
:    [junit4]   2> 3595723 T5910 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
:    [junit4]   2> 3595723 T5910 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
:    [junit4]   2> 3595723 T5910 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
:    [junit4]   2> 3595723 T5910 oasc.CoreContainer.load Host Name: 127.0.0.1
:    [junit4]   2> 3595724 T5910 oasc.ZkContainer.initZooKeeper Zookeeper 
client=127.0.0.1:53984/solr
:    [junit4]   2> 3595724 T5910 oasc.ZkController.checkChrootPath zkHost 
includes chroot
:    [junit4]   2> 3595725 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3595726 T5955 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@3727de80 
name:ZooKeeperConnection Watcher:127.0.0.1:53984 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3595727 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3595729 T5910 oascc.ConnectionManager.waitForConnected 
Waiting for client to connect to ZooKeeper
:    [junit4]   2> 3595730 T5957 oascc.ConnectionManager.process Watcher 
org.apache.solr.common.cloud.ConnectionManager@1a404f0c 
name:ZooKeeperConnection Watcher:127.0.0.1:53984/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
:    [junit4]   2> 3595730 T5910 oascc.ConnectionManager.waitForConnected 
Client is connected to ZooKeeper
:    [junit4]   2> 3595735 T5910 
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state 
from ZooKeeper... 
:    [junit4]   2> 3596738 T5910 oasc.ZkController.createEphemeralLiveNode 
Register node as live in ZooKeeper:/live_nodes/127.0.0.1:27736_
:    [junit4]   2> 3596739 T5910 oascc.SolrZkClient.makePath makePath: 
/live_nodes/127.0.0.1:27736_
:    [junit4]   2> 3596741 T5944 oascc.ZkStateReader$3.process Updating live 
nodes... (2)
:    [junit4]   2> 3596742 T5958 oascc.ZkStateReader$3.process Updating live 
nodes... (2)
:    [junit4]   2> 3596742 T5943 oascc.ZkStateReader$3.process Updating live 
nodes... (2)
:    [junit4]   2> 3596748 T5959 oasc.ZkController.publish publishing 
core=collection1 state=down collection=collection1
:    [junit4]   2> 3596748 T5959 oasc.ZkController.publish numShards not found 
on descriptor - reading it from system property
:    [junit4]   2> 3596749 T5944 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3596750 T5960 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3596749 T5959 oasc.ZkController.waitForCoreNodeName look for 
our core node name
:    [junit4]   2> 3596749 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3596751 T5933 oascc.ZkStateReader.updateClusterState 
Updating cloud state from ZooKeeper... 
:    [junit4]   2> 3596751 T5933 oasc.Overseer$ClusterStateUpdater.updateState 
Update state numShards=2 message={
:    [junit4]   2>        "operation":"state",
:    [junit4]   2>        "state":"down",
:    [junit4]   2>        "base_url":"http://127.0.0.1:27736";,
:    [junit4]   2>        "core":"collection1",
:    [junit4]   2>        "roles":null,
:    [junit4]   2>        "node_name":"127.0.0.1:27736_",
:    [junit4]   2>        "shard":null,
:    [junit4]   2>        "collection":"collection1",
:    [junit4]   2>        "numShards":"2",
:    [junit4]   2>        "core_node_name":null}
:    [junit4]   2> 3596751 T5933 
oasc.Overseer$ClusterStateUpdater.createCollection Create collection 
collection1 with shards [shard1, shard2]
:    [junit4]   2> 3596752 T5933 oasc.Overseer$ClusterStateUpdater.updateState 
Assigning new node to shard shard=shard2
:    [junit4]   2> 3596753 T5936 
oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on 
path: /overseer/queue state: SyncConnected type NodeChildrenChanged
:    [junit4]   2> 3596754 T5936 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
:    [junit4]   2> 3596754 T5958 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
:    [junit4]   2> 3596754 T5943 oascc.ZkStateReader$2.process A cluster state 
change: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
:    [junit4]   2> 3597750 T5959 oasc.ZkController.waitForShardId waiting to 
find shard id in clusterstate for collection1
:    [junit4]   2> 3597751 T5959 oasc.ZkController.createCollectionZkNode Check 
for collection zkNode:collection1
:    [junit4]   2> 3597751 T5959 oasc.ZkController.createCollectionZkNode 
Collection zkNode exists
:    [junit4]   2> 3597752 T5959 oascc.ZkStateReader.readConfigName Load 
collection config from:/collections/collection1
:    [junit4]   2> 3597752 T5959 oascc.ZkStateReader.readConfigName 
path=/collections/collection1 configName=conf1 specified config exists in 
ZooKeeper
:    [junit4]   2> 3597753 T5959 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/'
:    [junit4]   2> 3597754 T5959 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/lib/classes/'
 to classloader
:    [junit4]   2> 3597755 T5959 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/lib/.svn/'
 to classloader
:    [junit4]   2> 3597755 T5959 oasc.SolrResourceLoader.replaceClassLoader 
Adding 
'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/lib/README'
 to classloader
:    [junit4]   2> 3597819 T5959 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: LUCENE_4_10
:    [junit4]   2> 3597883 T5959 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
:    [junit4]   2> 3597984 T5959 oass.IndexSchema.readSchema Reading Solr 
Schema from schema.xml
:    [junit4]   2> 3597995 T5959 oass.IndexSchema.readSchema [collection1] 
Schema name=test
:    [junit4]   2> 3598432 T5959 oass.ByteField.init WARN ByteField is 
deprecated and will be removed in 5.0. You should use TrieIntField instead.
:    [junit4]   2> 3598433 T5959 oass.ShortField.init WARN ShortField is 
deprecated and will be removed in 5.0. You should use TrieIntField instead.
:    [junit4]   2> 3598433 T5959 oass.OpenExchangeRatesOrgProvider.init 
Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
:    [junit4]   2> 3598446 T5959 oass.IndexSchema.readSchema default search 
field in schema is text
:    [junit4]   2> 3598449 T5959 oass.IndexSchema.readSchema unique key field: 
id
:    [junit4]   2> 3598460 T5959 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 3598465 T5959 oass.FileExchangeRateProvider.reload Reloading 
exchange rates from file currency.xml
:    [junit4]   2> 3598469 T5959 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
:    [junit4]   2> 3598469 T5959 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
:    [junit4]   2> 3598470 T5959 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
:    [junit4]   2> 3598470 T5959 oass.OpenExchangeRatesOrgProvider.reload 
Reloading exchange rates from open-exchange-rates.json
:    [junit4]   2> 3598471 T5959 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key 
IMPORTANT NOTE
:    [junit4]   2> 3598471 T5959 
oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, 
got STRING
:    [junit4]   2> 3598471 T5959 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from collection collection1
:    [junit4]   2> 3598471 T5959 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
:    [junit4]   2> 3598472 T5959 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/,
 
dataDir=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/data/
:    [junit4]   2> 3598472 T5959 oasc.JmxMonitoredMap.<init> JMX monitoring is 
enabled. Adding Solr mbeans to JMX Server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bde656e
:    [junit4]   2> 3598473 T5959 oasc.CachingDirectoryFactory.get return new 
directory for 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/data
:    [junit4]   2> 3598473 T5959 oasc.SolrCore.getNewIndexDir New index 
directory detected: old=null 
new=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/data/index/
:    [junit4]   2> 3598473 T5959 oasc.SolrCore.initIndex WARN [collection1] 
Solr index directory 
'./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/data/index'
 doesn't exist. Creating new index...
:    [junit4]   2> 3598474 T5959 oasc.CachingDirectoryFactory.get return new 
directory for 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-003/collection1/data/index
:    [junit4]   2> 3598474 T5959 oasu.RandomMergePolicy.<init> 
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: 
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, 
maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
:    [junit4]   2> 3598475 T5959 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
:    [junit4]   2>              
commit{dir=MockDirectoryWrapper(RAMDirectory@34b9c3ed 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@175c4ba0),segFN=segments_1,generation=1}
:    [junit4]   2> 3598475 T5959 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
:    [junit4]   2> 3598480 T5959 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "nodistrib"
:    [junit4]   2> 3598480 T5959 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "dedupe"
:    [junit4]   2> 3598480 T5959 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe"
:    [junit4]   2> 3598480 T5959 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "stored_sig"
:    [junit4]   2> 3598481 T5959 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"stored_sig"
:    [junit4]   2> 3598481 T5959 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
:    [junit4]   2> 3598481 T5959 oasup.UpdateRequestProcessorChain.init 
creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
:    [junit4]   2> 3598482 T5959 oasup.UpdateRequestProcessorChain.init 
inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
:    [junit4]   2> 3598482 T5959 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
:    [junit4]   2> 3598483 T5959 oasc.RequestHandlers.initHandlersFromConfig 
created /update: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3598483 T5959 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3598484 T5959 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3598484 T5959 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
:    [junit4]   2> 3598485 T5959 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
:    [junit4]   2> 3598486 T5959 oasc.RequestHandlers.initHandlersFromConfig 
created /get: solr.RealTimeGetHandler
:    [junit4]   2> 3598486 T5959 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.ReplicationHandler
:    [junit4]   2> 3598486 T5959 oasc.RequestHandlers.initHandlersFromConfig 
create
: 
: [...truncated too long message...]
: 
: 3645985 T5910 oasc.CachingDirectoryFactory.close Closing directory: 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest1_shard3_replica1/data/index
:    [junit4]   2> 3645986 T5910 oasc.SolrCore.close 
[ocptest_shardsplit_shard4_replica1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2cc6d31a
:    [junit4]   2> 3645994 T5910 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,transaction_logs_total_size=0,transaction_logs_total_number=0}
:    [junit4]   2> 3645994 T5910 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
:    [junit4]   2> 3645994 T5910 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
:    [junit4]   2> 3645995 T5910 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
:    [junit4]   2> 3645995 T5910 oasc.SolrCore.closeSearcher 
[ocptest_shardsplit_shard4_replica1] Closing main searcher on request.
:    [junit4]   2> 3645996 T5910 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
:    [junit4]   2> 3645996 T5910 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest_shardsplit_shard4_replica1/data/index
 
[CachedDir<<refCount=0;path=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest_shardsplit_shard4_replica1/data/index;done=false>>]
:    [junit4]   2> 3645997 T5910 oasc.CachingDirectoryFactory.close Closing 
directory: 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest_shardsplit_shard4_replica1/data/index
:    [junit4]   2> 3645997 T5910 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest_shardsplit_shard4_replica1/data
 
[CachedDir<<refCount=0;path=./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest_shardsplit_shard4_replica1/data;done=false>>]
:    [junit4]   2> 3645997 T5910 oasc.CachingDirectoryFactory.close Closing 
directory: 
./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001/tempDir-006/ocptest_shardsplit_shard4_replica1/data
:    [junit4]   2> 3645998 T7442 oasc.Overseer$ClusterStateUpdater.run Overseer 
Loop exiting : 127.0.0.1:18528_
:    [junit4]   2> 3646000 T7437 oascc.ZkStateReader$3.process WARN ZooKeeper 
watch triggered, but Solr cannot talk to ZK
:    [junit4]   2> 3646000 T7444 
oasc.Overseer$ClusterStateUpdater.checkIfIamStillLeader ERROR could not read 
the data org.apache.zookeeper.KeeperException$SessionExpiredException: 
KeeperErrorCode = Session expired for /overseer_elect/leader
:    [junit4]   2>      at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
:    [junit4]   2>      at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
:    [junit4]   2>      at 
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
:    [junit4]   2>      at 
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:302)
:    [junit4]   2>      at 
org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:299)
:    [junit4]   2>      at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
:    [junit4]   2>      at 
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:299)
:    [junit4]   2>      at 
org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:316)
:    [junit4]   2>      at 
org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:85)
:    [junit4]   2>      at 
org.apache.solr.cloud.Overseer$ClusterStateUpdater$1.run(Overseer.java:288)
:    [junit4]   2> 
:    [junit4]   2> 3646001 T5910 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/,null}
:    [junit4]   2> 3650916 T5910 oas.SolrTestCaseJ4.tearDown ###Ending 
testDistribSearch
:    [junit4]   2> 3650918 T5910 oasc.ZkTestServer.send4LetterWord connecting 
to 127.0.0.1:53984 53984
:    [junit4]   2> 3651144 T5911 oasc.ZkTestServer.send4LetterWord connecting 
to 127.0.0.1:53984 53984
:    [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=MultiThreadedOCPTest -Dtests.method=testDistribSearch 
-Dtests.seed=894023E3DFD774C6 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ar_DZ -Dtests.timezone=Europe/Vilnius 
-Dtests.file.encoding=ISO-8859-1
:    [junit4] FAILURE 60.0s J0 | MultiThreadedOCPTest.testDistribSearch <<<
:    [junit4]    > Throwable #1: java.lang.AssertionError: We have a failed 
SPLITSHARD task
:    [junit4]    >      at 
__randomizedtesting.SeedInfo.seed([894023E3DFD774C6:8A6ADFBA88814FA]:0)
:    [junit4]    >      at 
org.apache.solr.cloud.MultiThreadedOCPTest.testTaskExclusivity(MultiThreadedOCPTest.java:125)
:    [junit4]    >      at 
org.apache.solr.cloud.MultiThreadedOCPTest.doTest(MultiThreadedOCPTest.java:71)
:    [junit4]    >      at 
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:867)
:    [junit4]    >      at java.lang.Thread.run(Thread.java:745)
:    [junit4]   2> 3651162 T5910 oas.SolrTestCaseJ4.deleteCore ###deleteCore
:    [junit4]   2> NOTE: leaving temporary files on disk at: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build/solr-core/test/J0/./solr.cloud.MultiThreadedOCPTest-894023E3DFD774C6-001
:    [junit4]   2> 59977 T5909 ccr.ThreadLeakControl.checkThreadLeaks WARNING 
Will linger awaiting termination of 1 leaked thread(s).
:    [junit4]   2> NOTE: test params are: codec=Lucene41, 
sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_DZ, 
timezone=Europe/Vilnius
:    [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 
1.7.0_60 (64-bit)/cpus=16,threads=1,free=278470064,total=529530880
:    [junit4]   2> NOTE: All tests run in this JVM: [DirectUpdateHandlerTest, 
CoreAdminCreateDiscoverTest, TestQuerySenderListener, MinimalSchemaTest, 
TestSolrDeletionPolicy1, CollectionsAPIDistributedZkTest, 
TestLuceneMatchVersion, TestQuerySenderNoQuery, TestManagedStopFilterFactory, 
TestDynamicFieldCollectionResource, TestSearchPerf, SortByFunctionTest, 
TestStressRecovery, TestCustomSort, SuggesterFSTTest, TestFaceting, 
TestStressLucene, TestChildDocTransformer, DirectSolrConnectionTest, 
EnumFieldTest, SolrIndexSplitterTest, IndexBasedSpellCheckerTest, TestDocSet, 
DocValuesMultiTest, CustomCollectionTest, LegacyHTMLStripCharFilterTest, 
TestSchemaVersionResource, ConnectionManagerTest, TestMergePolicyConfig, 
TestPseudoReturnFields, TestFieldSortValues, OverseerRolesTest, DateFieldTest, 
TermVectorComponentDistributedTest, TestIBSimilarityFactory, 
TestSolrXmlPersistence, FieldAnalysisRequestHandlerTest, 
DistribCursorPagingTest, SimpleFacetsTest, DistanceFunctionTest, 
TestSolrXMLSeria
 lizer, TestExtendedDismaxParser, HdfsBasicDistributedZk2Test, 
DocumentBuilderTest, FileUtilsTest, UpdateParamsTest, 
DocumentAnalysisRequestHandlerTest, UnloadDistributedZkTest, CircularListTest, 
ZkNodePropsTest, ScriptEngineTest, BasicZkTest, 
DistributedQueryComponentOptimizationTest, TestCSVResponseWriter, 
ParsingFieldUpdateProcessorsTest, HdfsRecoveryZkTest, OverseerStatusTest, 
StatsComponentTest, TestWriterPerf, ClusterStateUpdateTest, 
TestUniqueKeyFieldResource, TestNumberUtils, QueryEqualityTest, 
DocExpirationUpdateProcessorFactoryTest, TestComponentsName, TestConfigSets, 
RequiredFieldsTest, TestRestManager, TestLMJelinekMercerSimilarityFactory, 
DateMathParserTest, SuggestComponentTest, LeaderElectionTest, 
MergeStrategyTest, ReplicationFactorTest, URLClassifyProcessorTest, 
TestFileDictionaryLookup, PrimUtilsTest, BlockCacheTest, SuggesterTest, 
SolrIndexConfigTest, FastVectorHighlighterTest, TestSolr4Spatial, 
BasicDistributedZkTest, TestUtils, BasicFunctionalityTest, BlockDirect
 oryTest, TestRecovery, ExternalFileFieldSortTest, TestGroupingSearch, 
TestSolrJ, DistributedDebugComponentTest, TestSchemaSimilarityResource, 
CoreMergeIndexesAdminHandlerTest, TestDocumentBuilder, TestSolr4Spatial2, 
TestSolrDeletionPolicy2, TestManagedResourceStorage, TestCharFilters, 
UniqFieldsUpdateProcessorFactoryTest, ShardSplitTest, 
TestCloudManagedSchemaConcurrent, MultiThreadedOCPTest]
:    [junit4] Completed on J0 in 60.51s, 1 test, 1 failure <<< FAILURES!
: 
: [...truncated 627 lines...]
: BUILD FAILED
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:474:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:454:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/build.xml:45:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/extra-targets.xml:37:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/build.xml:189:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/solr/common-build.xml:496:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/lucene/common-build.xml:1299:
 The following error occurred while executing this line:
: 
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-Java7/lucene/common-build.xml:923:
 There were test failures: 411 suites, 1711 tests, 1 suite-level error, 1 
failure, 53 ignored (19 assumptions)
: 
: Total time: 123 minutes 13 seconds
: Build step 'Invoke Ant' marked build as failure
: Archiving artifacts
: Sending artifact delta relative to Lucene-Solr-Tests-4.x-Java7 #2040
: Archived 3 artifacts
: Archive block size is 32768
: Received 0 blocks and 196987264 bytes
: Compression is 0.0%
: Took 36 sec
: Recording test results
: Email was triggered for: Failure
: Sending email for trigger: Failure
: 
: 
: 

-Hoss
http://www.lucidworks.com/
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to