Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/738/

2 tests failed.
FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=16156, name=collection5, 
state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=16156, name=collection5, state=RUNNABLE, 
group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:49602: Could not find collection : 
awholynewstresscollection_collection5_0
        at __randomizedtesting.SeedInfo.seed([34491A4993AAC335]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
        at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)


REGRESSION:  org.apache.solr.search.function.SortByFunctionTest.test

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([34491A4993AAC335:BC1D25933D56AECD]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:763)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:730)
        at 
org.apache.solr.search.function.SortByFunctionTest.test(SortByFunctionTest.java:49)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:483)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        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:845)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        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:54)
        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)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result/doc[1]/int[@name='id'][.='1']
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="4" start="0" 
maxScore="1.0"><doc><int name="id">3</int><arr 
name="range_facet_l"><long>3</long></arr><arr 
name="range_facet_l_dv"><long>3</long></arr><arr 
name="range_facet_i_dv"><int>3</int></arr><double 
name="x_td1">3.0</double><double name="y_td1">2.0</double><double 
name="w_td1">55.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504751755264</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.214Z</date><float 
name="score">1.0</float></doc><doc><int name="id">4</int><arr 
name="range_facet_l"><long>4</long></arr><arr 
name="range_facet_l_dv"><long>4</long></arr><arr 
name="range_facet_i_dv"><int>4</int></arr><double 
name="x_td1">4.0</double><double name="y_td1">2.0</double><double 
name="w_td1">45.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504752803840</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.215Z</date><float 
name="score">1.0</float></doc><doc><int name="id">1</int><arr 
name="range_facet_l"><long>1</long></arr><arr 
name="range_facet_l_dv"><long>1</long></arr><arr 
name="range_facet_i_dv"><int>1</int></arr><double 
name="x_td1">0.0</double><double name="y_td1">2.0</double><double 
name="w_td1">25.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod</str></arr><long 
name="_version_">1506567504740220928</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.203Z</date><float 
name="score">1.0</float></doc><doc><int name="id">2</int><arr 
name="range_facet_l"><long>2</long></arr><arr 
name="range_facet_l_dv"><long>2</long></arr><arr 
name="range_facet_i_dv"><int>2</int></arr><double 
name="x_td1">2.0</double><double name="y_td1">2.0</double><double 
name="w_td1">15.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504746512384</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.209Z</date><float 
name="score">1.0</float></doc></result>
</response>

        request was:q=*:*&fl=*,score&wt=xml
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:756)
        ... 41 more




Build Log:
[...truncated 11025 lines...]
   [junit4] Suite: org.apache.solr.search.function.SortByFunctionTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001
   [junit4]   2> 2251965 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 2251966 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 2251966 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 2251967 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 2251967 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2251967 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2251991 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2252000 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2252021 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] o.a.s.c.Config 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2252021 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 2252026 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 2252121 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2252126 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2252127 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2252131 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2252132 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2252134 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2252134 WARN  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2252134 WARN  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2252134 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2252135 WARN  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2252135 WARN  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2252135 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 2252135 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr
   [junit4]   2> 2252135 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/'
   [junit4]   2> 2252148 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 574873837
   [junit4]   2> 2252148 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/]
   [junit4]   2> 2252148 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib
   [junit4]   2> 2252148 WARN  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/lib).
   [junit4]   2> 2252154 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2252156 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 2252156 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2252156 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2252156 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 2252157 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, 
config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, 
instanceDir=collection1, collection=collection1, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/,
 
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001,
 shard=shard1}
   [junit4]   2> 2252158 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 2252159 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/.svn/'
 to classloader
   [junit4]   2> 2252159 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 2252159 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 2252180 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 2252188 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 2252210 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 2252211 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
   [junit4]   2> 2252217 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2252325 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 2252331 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 2252334 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 2252338 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2252340 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 2252341 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2252341 WARN  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2252342 WARN  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2252342 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 2252342 WARN  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 2252342 WARN  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 2252342 INFO  (coreLoadExecutor-5515-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/
   [junit4]   2> 2252342 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 2252343 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/],
 dataDir=[null]
   [junit4]   2> 2252343 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1a219006
   [junit4]   2> 2252343 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001
   [junit4]   2> 2252343 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/index/
   [junit4]   2> 2252343 WARN  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 2252344 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/index
   [junit4]   2> 2252344 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=20, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 2252344 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_1,generation=1}
   [junit4]   2> 2252344 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2252346 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/
   [junit4]   2> 2252353 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 2252353 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 2252353 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe-allfields"
   [junit4]   2> 2252353 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"dedupe-allfields"
   [junit4]   2> 2252353 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2252353 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 2252354 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2252354 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
   [junit4]   2> 2252354 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 2252354 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 2252354 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain 
"distrib-dup-test-chain-implicit"
   [junit4]   2> 2252354 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 2252356 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2252357 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2252358 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2252359 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 2252362 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with 
startup=lazy 
   [junit4]   2> 2252363 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
   [junit4]   2> 2252364 WARN  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated 
<admin/gettableFiles>, Please update your config to use the 
ShowFileRequestHandler.
   [junit4]   2> 2252364 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 2252365 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2252365 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2252366 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 2252366 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 2252366 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=2, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=35.8935546875, 
floorSegmentMB=1.88671875, forceMergeDeletesPctAllowed=3.3219582670736725, 
segmentsPerTier=30.0, maxCFSSegmentSizeMB=0.6103515625, noCFSRatio=1.0
   [junit4]   2> 2252367 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_1,generation=1}
   [junit4]   2> 2252367 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 2252368 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4fd00e8f[collection1] 
main
   [junit4]   2> 2252368 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: 
{storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
   [junit4]   2> 2252368 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 2252368 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 2252368 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2252378 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2252408 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2252409 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2252410 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed 
version buckets
   [junit4]   2> 2252410 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
65536 version buckets from index
   [junit4]   2> 2252410 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed 
version bucket highest value from index
   [junit4]   2> 2252410 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1506567504726589440
   [junit4]   2> 2252411 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2252411 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2252412 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2252413 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2252413 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest 
version 1506567504726589440
   [junit4]   2> 2252413 INFO  (coreLoadExecutor-5515-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 2252413 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2252415 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 2252421 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2252422 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2252423 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2252425 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2252427 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[1 (1506567504740220928)]} 0 3
   [junit4]   2> 2252427 INFO  
(searcherExecutor-5516-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4fd00e8f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2252433 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[2 (1506567504746512384)]} 0 4
   [junit4]   2> 2252435 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[3 (1506567504751755264)]} 0 0
   [junit4]   2> 2252463 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[4 (1506567504752803840)]} 0 28
   [junit4]   2> 2252464 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2252465 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] o.a.s.c.SolrCore 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_2,generation=2}
   [junit4]   2> 2252466 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] o.a.s.c.SolrCore 
newest commit generation = 2
   [junit4]   2> 2252474 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@70ef2384[collection1] main
   [junit4]   2> 2252475 INFO  (searcherExecutor-5516-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@70ef2384[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.0.0):c4)))}
   [junit4]   2> 2252476 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2252476 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 12
   [junit4]   2> 2252477 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] o.a.s.c.S.Request 
[collection1] webapp=null path=null params={q=*:*&fl=*,score&wt=xml} hits=4 
status=0 QTime=0 
   [junit4]   2> 2252478 ERROR 
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 REQUEST FAILED: 
xpath=//result/doc[1]/int[@name='id'][.='1']
   [junit4]   2>        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="4" start="0" 
maxScore="1.0"><doc><int name="id">3</int><arr 
name="range_facet_l"><long>3</long></arr><arr 
name="range_facet_l_dv"><long>3</long></arr><arr 
name="range_facet_i_dv"><int>3</int></arr><double 
name="x_td1">3.0</double><double name="y_td1">2.0</double><double 
name="w_td1">55.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504751755264</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.214Z</date><float 
name="score">1.0</float></doc><doc><int name="id">4</int><arr 
name="range_facet_l"><long>4</long></arr><arr 
name="range_facet_l_dv"><long>4</long></arr><arr 
name="range_facet_i_dv"><int>4</int></arr><double 
name="x_td1">4.0</double><double name="y_td1">2.0</double><double 
name="w_td1">45.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504752803840</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.215Z</date><float 
name="score">1.0</float></doc><doc><int name="id">1</int><arr 
name="range_facet_l"><long>1</long></arr><arr 
name="range_facet_l_dv"><long>1</long></arr><arr 
name="range_facet_i_dv"><int>1</int></arr><double 
name="x_td1">0.0</double><double name="y_td1">2.0</double><double 
name="w_td1">25.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod</str></arr><long 
name="_version_">1506567504740220928</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.203Z</date><float 
name="score">1.0</float></doc><doc><int name="id">2</int><arr 
name="range_facet_l"><long>2</long></arr><arr 
name="range_facet_l_dv"><long>2</long></arr><arr 
name="range_facet_i_dv"><int>2</int></arr><double 
name="x_td1">2.0</double><double name="y_td1">2.0</double><double 
name="w_td1">15.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504746512384</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.209Z</date><float 
name="score">1.0</float></doc></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2>        request was:q=*:*&fl=*,score&wt=xml
   [junit4]   2> 2252479 ERROR 
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 REQUEST FAILED: 
q=*:*&fl=*,score&wt=xml:java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result/doc[1]/int[@name='id'][.='1']
   [junit4]   2>        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="4" start="0" 
maxScore="1.0"><doc><int name="id">3</int><arr 
name="range_facet_l"><long>3</long></arr><arr 
name="range_facet_l_dv"><long>3</long></arr><arr 
name="range_facet_i_dv"><int>3</int></arr><double 
name="x_td1">3.0</double><double name="y_td1">2.0</double><double 
name="w_td1">55.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504751755264</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.214Z</date><float 
name="score">1.0</float></doc><doc><int name="id">4</int><arr 
name="range_facet_l"><long>4</long></arr><arr 
name="range_facet_l_dv"><long>4</long></arr><arr 
name="range_facet_i_dv"><int>4</int></arr><double 
name="x_td1">4.0</double><double name="y_td1">2.0</double><double 
name="w_td1">45.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504752803840</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.215Z</date><float 
name="score">1.0</float></doc><doc><int name="id">1</int><arr 
name="range_facet_l"><long>1</long></arr><arr 
name="range_facet_l_dv"><long>1</long></arr><arr 
name="range_facet_i_dv"><int>1</int></arr><double 
name="x_td1">0.0</double><double name="y_td1">2.0</double><double 
name="w_td1">25.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod</str></arr><long 
name="_version_">1506567504740220928</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.203Z</date><float 
name="score">1.0</float></doc><doc><int name="id">2</int><arr 
name="range_facet_l"><long>2</long></arr><arr 
name="range_facet_l_dv"><long>2</long></arr><arr 
name="range_facet_i_dv"><int>2</int></arr><double 
name="x_td1">2.0</double><double name="y_td1">2.0</double><double 
name="w_td1">15.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504746512384</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.209Z</date><float 
name="score">1.0</float></doc></result>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2>        request was:q=*:*&fl=*,score&wt=xml
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:756)
   [junit4]   2>        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:730)
   [junit4]   2>        at 
org.apache.solr.search.function.SortByFunctionTest.test(SortByFunctionTest.java:49)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:483)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 2252480 INFO  
(TEST-SortByFunctionTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SortByFunctionTest 
-Dtests.method=test -Dtests.seed=34491A4993AAC335 -Dtests.multiplier=2 
-Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt 
-Dtests.locale=no -Dtests.timezone=America/Cuiaba -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.07s J1 | SortByFunctionTest.test <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during 
query
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([34491A4993AAC335:BC1D25933D56AECD]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:763)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:730)
   [junit4]    >        at 
org.apache.solr.search.function.SortByFunctionTest.test(SortByFunctionTest.java:49)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result/doc[1]/int[@name='id'][.='1']
   [junit4]    >        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="4" start="0" 
maxScore="1.0"><doc><int name="id">3</int><arr 
name="range_facet_l"><long>3</long></arr><arr 
name="range_facet_l_dv"><long>3</long></arr><arr 
name="range_facet_i_dv"><int>3</int></arr><double 
name="x_td1">3.0</double><double name="y_td1">2.0</double><double 
name="w_td1">55.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504751755264</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.214Z</date><float 
name="score">1.0</float></doc><doc><int name="id">4</int><arr 
name="range_facet_l"><long>4</long></arr><arr 
name="range_facet_l_dv"><long>4</long></arr><arr 
name="range_facet_i_dv"><int>4</int></arr><double 
name="x_td1">4.0</double><double name="y_td1">2.0</double><double 
name="w_td1">45.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504752803840</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.215Z</date><float 
name="score">1.0</float></doc><doc><int name="id">1</int><arr 
name="range_facet_l"><long>1</long></arr><arr 
name="range_facet_l_dv"><long>1</long></arr><arr 
name="range_facet_i_dv"><int>1</int></arr><double 
name="x_td1">0.0</double><double name="y_td1">2.0</double><double 
name="w_td1">25.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod</str></arr><long 
name="_version_">1506567504740220928</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.203Z</date><float 
name="score">1.0</float></doc><doc><int name="id">2</int><arr 
name="range_facet_l"><long>2</long></arr><arr 
name="range_facet_l_dv"><long>2</long></arr><arr 
name="range_facet_i_dv"><int>2</int></arr><double 
name="x_td1">2.0</double><double name="y_td1">2.0</double><double 
name="w_td1">15.0</double><double name="z_td1">5.0</double><arr 
name="f_t"><str>ipod ipod ipod ipod ipod</str></arr><long 
name="_version_">1506567504746512384</long><arr 
name="multiDefault"><str>muLti-Default</str></arr><int 
name="intDefault">42</int><date 
name="timestamp">2015-07-13T08:05:35.209Z</date><float 
name="score">1.0</float></doc></result>
   [junit4]    > </response>
   [junit4]    >        request was:q=*:*&fl=*,score&wt=xml
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:756)
   [junit4]    >        ... 41 more
   [junit4]   2> 2252492 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testSortJoinDocFreq
   [junit4]   2> 2252494 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[4 (1506567504813621248)]} 0 1
   [junit4]   2> 2252623 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[3 (1506567504817815552)]} 0 128
   [junit4]   2> 2252624 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2252624 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_3,generation=3}
   [junit4]   2> 2252624 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 2252626 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@383ccf9e[collection1] main
   [junit4]   2> 2252626 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2252627 INFO  (searcherExecutor-5516-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@383ccf9e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.0.0):c4)))}
   [junit4]   2> 2252627 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 3
   [junit4]   2> 2252629 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[2 (1506567504954130432)]} 0 1
   [junit4]   2> 2252641 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{add=[1 (1506567504956227584)]} 0 11
   [junit4]   2> 2252642 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2252643 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@e64a1ff 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@73d74c20),segFN=segments_4,generation=4}
   [junit4]   2> 2252643 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrCore newest commit generation = 4
   [junit4]   2> 2252645 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@5db7631c[collection1] main
   [junit4]   2> 2252645 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 2252646 INFO  (searcherExecutor-5516-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5db7631c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(6.0.0):c4)))}
   [junit4]   2> 2252646 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 4
   [junit4]   2> 2252648 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=links_mfacet:B&fl=id&sort=id+asc&wt=xml} hits=2 status=0 QTime=0 
   [junit4]   2> 2252651 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&fl=id&sort=joindf(id_s1,+links_mfacet)+desc&wt=xml} hits=4 
status=0 QTime=2 
   [junit4]   2> 2252652 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&fl=id&sort=joindf(id_s1,+links_mfacet)+asc&wt=xml} hits=4 
status=0 QTime=0 
   [junit4]   2> 2252653 INFO  
(TEST-SortByFunctionTest.testSortJoinDocFreq-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testSortJoinDocFreq
   [junit4]   2> 2252653 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 2252654 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=574873837
   [junit4]   2> 2252654 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1dbb39fb
   [junit4]   2> 2252655 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=3,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=8,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=726,transaction_logs_total_number=3}
   [junit4]   2> 2252655 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 2252655 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing 
IndexWriter
   [junit4]   2> 2252655 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2252655 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 2252672 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories 
currently being tracked
   [junit4]   2> 2252672 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001;done=false>>]
   [junit4]   2> 2252672 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001
   [junit4]   2> 2252672 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory looking to close 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 2252673 INFO  
(SUITE-SortByFunctionTest-seed#[34491A4993AAC335]-worker) [    x:collection1] 
o.a.s.c.CachingDirectoryFactory Closing directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.search.function.SortByFunctionTest_34491A4993AAC335-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): 
{w_td1=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_l_dv=BlockTreeOrds(blocksize=128), 
multiDefault=Lucene50(blocksize=128), intDefault=PostingsFormat(name=Memory 
doPackFST= true), y_td1=BlockTreeOrds(blocksize=128), 
range_facet_l=PostingsFormat(name=Memory doPackFST= true), 
id_s1=PostingsFormat(name=Memory doPackFST= true), 
_version_=PostingsFormat(name=Memory doPackFST= true), 
z_td1=Lucene50(blocksize=128), f_t=BlockTreeOrds(blocksize=128), 
links_mfacet=Lucene50(blocksize=128), id=BlockTreeOrds(blocksize=128), 
range_facet_i_dv=PostingsFormat(name=Memory doPackFST= true), 
text=PostingsFormat(name=LuceneVarGapFixedInterval), 
x_td1=PostingsFormat(name=Memory doPackFST= true), 
timestamp=PostingsFormat(name=Memory doPackFST= true)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), 
range_facet_i_dv=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=no, 
timezone=America/Cuiaba
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_25 (64-bit)/cpus=4,threads=1,free=93223192,total=531628032
   [junit4]   2> NOTE: All tests run in this JVM: [TestFastLRUCache, 
TestNRTOpen, SpellPossibilityIteratorTest, TestRandomMergePolicy, 
DocValuesMultiTest, TestSerializedLuceneMatchVersion, SpatialFilterTest, 
ZkSolrClientTest, TestDefaultSearchFieldResource, CachingDirectoryFactoryTest, 
TestHashPartitioner, InfoHandlerTest, FieldAnalysisRequestHandlerTest, 
TestLFUCache, NoCacheHeaderTest, TestJettySolrRunner, 
TestLeaderElectionZkExpiry, PreAnalyzedFieldTest, TestValueSourceCache, 
TestCustomDocTransformer, ReturnFieldsTest, TestBulkSchemaConcurrent, 
CopyFieldTest, TestRTGBase, TestBulkSchemaAPI, 
TestLMDirichletSimilarityFactory, TestOverriddenPrefixQueryForCustomFieldType, 
ZkCLITest, SystemInfoHandlerTest, SolrCmdDistributorTest, 
StatelessScriptUpdateProcessorFactoryTest, TestFoldingMultitermQuery, 
ConcurrentDeleteAndCreateCollectionTest, TestSQLHandler, 
TestDistribDocBasedVersion, DistributedExpandComponentTest, ResourceLoaderTest, 
TestCloudManagedSchema, TestFiltering, HdfsBasicDistributedZkTest, 
SaslZkACLProviderTest, AddSchemaFieldsUpdateProcessorFactoryTest, 
TestAuthenticationFramework, TestIndexSearcher, TestCustomSort, JSONWriterTest, 
TestSolrConfigHandlerCloud, EchoParamsTest, WordBreakSolrSpellCheckerTest, 
AnalyticsQueryTest, AsyncCallRequestStatusResponseTest, RemoteQueryErrorTest, 
BlockCacheTest, TestDistributedMissingSort, BasicFunctionalityTest, 
SuggestComponentTest, TestComplexPhraseQParserPlugin, DistributedQueueTest, 
LukeRequestHandlerTest, DocumentAnalysisRequestHandlerTest, 
AnalysisAfterCoreReloadTest, ShowFileRequestHandlerTest, 
ChaosMonkeySafeLeaderTest, SSLMigrationTest, HdfsRecoverLeaseTest, 
FileUtilsTest, SpellCheckComponentTest, DistanceFunctionTest, 
HdfsThreadLeakTest, CoreAdminCreateDiscoverTest, DisMaxRequestHandlerTest, 
TestConfigSets, CoreAdminRequestStatusTest, BaseCdcrDistributedZkTest, 
IndexSchemaRuntimeFieldTest, UpdateParamsTest, TestDynamicLoading, 
TestWriterPerf, SuggesterTSTTest, ShardRoutingCustomTest, 
TestSolrQueryParserResource, TestExpandComponent, RankQueryTest, 
BasicDistributedZkTest, OpenCloseCoreStressTest, AutoCommitTest, 
TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, 
TermVectorComponentDistributedTest, ZkControllerTest, TestJoin, TestReload, 
TestStressVersions, DistributedTermsComponentTest, TestCoreContainer, 
SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, PeerSyncTest, 
ConvertedLegacyTest, TestSort, TestFunctionQuery, DirectUpdateHandlerTest, 
SoftAutoCommitTest, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, 
SimplePostToolTest, TestCoreDiscovery, TestExtendedDismaxParser, 
CoreAdminHandlerTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, 
SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestUpdate, 
FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, 
DirectUpdateHandlerOptimizeTest, SortByFunctionTest]
   [junit4] Completed [305/515] on J1 in 0.73s, 2 tests, 1 error <<< FAILURES!

[...truncated 670 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/init-core-data-001
   [junit4]   2> 2838987 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 2838988 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 2839068 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried 
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 2839079 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 2839084 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 2839109 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs
 to ./temp/Jetty_localhost_40662_hdfs____1xc80/webapp
   [junit4]   2> 2839216 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 2839743 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40662
   [junit4]   2> 2839823 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 2839824 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 2839838 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
 to ./temp/Jetty_localhost_58645_datanode____.b63gbp/webapp
   [junit4]   2> 2839933 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 2840369 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58645
   [junit4]   2> 2840443 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 2840444 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 2840461 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
 to ./temp/Jetty_localhost_40446_datanode____.kpy7a7/webapp
   [junit4]   2> 2840547 INFO  (IPC Server handler 5 on 34693) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-b6e4da97-7c4f-4360-8904-2434c87a22f6 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=8ed62380-8e04-4136-89a1-dce532a0bec2, infoPort=58645, 
ipcPort=49424, storageInfo=lv=-56;cid=testClusterID;nsid=1543738071;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 2840548 INFO  (IPC Server handler 5 on 34693) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-64106c7d-dde5-46e8-ab81-d71cbf6fcf75 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=8ed62380-8e04-4136-89a1-dce532a0bec2, infoPort=58645, 
ipcPort=49424, storageInfo=lv=-56;cid=testClusterID;nsid=1543738071;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 2840603 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 2841204 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40446
   [junit4]   2> 2841294 INFO  (IPC Server handler 6 on 34693) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-fe841820-1c17-401f-9875-75d8d462610e node DatanodeRegistration(127.0.0.1, 
datanodeUuid=593984e5-22c0-40b1-979c-1b0081d0c1d6, infoPort=40446, 
ipcPort=38240, storageInfo=lv=-56;cid=testClusterID;nsid=1543738071;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 2841295 INFO  (IPC Server handler 6 on 34693) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-f8034be5-fb3a-4125-9c3f-6c7416e512cb node DatanodeRegistration(127.0.0.1, 
datanodeUuid=593984e5-22c0-40b1-979c-1b0081d0c1d6, infoPort=40446, 
ipcPort=38240, storageInfo=lv=-56;cid=testClusterID;nsid=1543738071;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 2841412 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2841413 INFO  (Thread-6856) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2841413 INFO  (Thread-6856) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2841513 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:42136
   [junit4]   2> 2841513 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2841514 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2841517 INFO  (zkCallback-1470-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1f5523b2 
name:ZooKeeperConnection Watcher:127.0.0.1:42136 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2841517 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2841517 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2841518 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 2841523 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2841524 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2841524 INFO  (zkCallback-1471-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4ddf47fa 
name:ZooKeeperConnection Watcher:127.0.0.1:42136/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2841525 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2841525 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2841525 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 2841526 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 2841528 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 2841529 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 2841530 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 2841530 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 2841532 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 2841532 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 2841534 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2841534 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 2841536 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 2841536 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 2841537 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 2841538 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 2841539 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 2841540 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 2841541 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 2841541 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 2841543 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2841543 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 2841545 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2841545 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 2841546 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 2841547 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 2841548 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 2841548 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 2841831 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/cores/collection1
   [junit4]   2> 2841833 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 2841834 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3a548cc4{/,null,AVAILABLE}
   [junit4]   2> 2841835 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@50b243d7{HTTP/1.1}{127.0.0.1:49602}
   [junit4]   2> 2841835 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.e.j.s.Server Started @2845576ms
   [junit4]   2> 2841835 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=hdfs://localhost:34693/hdfs__localhost_34693__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001_tempDir-002_control_data,
 hostContext=/, hostPort=49602, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/cores}
   [junit4]   2> 2841835 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 2841835 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/'
   [junit4]   2> 2841848 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/solr.xml
   [junit4]   2> 2841853 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/cores
   [junit4]   2> 2841853 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1897585164
   [junit4]   2> 2841853 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/]
   [junit4]   2> 2841853 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/lib
   [junit4]   2> 2841854 WARN  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_34491A4993AAC335-001/control-001/lib).
   [junit4]   2> 2841860 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme 
: ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 2841861 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2841862 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 2841862 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 2841862 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42136/solr
   [junit4]   2> 2841862 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 2841862 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 2841863 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2841864 INFO  (zkCallback-1473-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@138fc8b0 
name:ZooKeeperConnection Watcher:127.0.0.1:42136 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2841865 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2841865 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 2841867 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2841867 INFO  
(zkCallback-1474-thread-1-processing-n:127.0.0.1:49602_) [n:127.0.0.1:49602_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4b37d6b4 
name:ZooKeeperConnection Watcher:127.0.0.1:42136/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 2841868 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 2841869 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 2841870 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 2841872 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 2841874 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 2841876 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 2841878 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 2841879 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 2841880 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 2841881 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:49602_
   [junit4]   2> 2841882 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:49602_
   [junit4]   2> 2841884 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 2841885 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 2841887 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2841887 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/94160507627175939-127.0.0.1:49602_-n_0000000000
   [junit4]   2> 2841888 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:49602_
   [junit4]   2> 2841888 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 2841890 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.Overseer Overseer 
(id=94160507627175939-127.0.0.1:49602_-n_0000000000) starting
   [junit4]   2> 2841891 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 2841898 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 2841898 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[34491A4993AAC335]) 
[n:127.0.0.1:49602_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 2841898 INFO  
(OverseerCollectionProcessor-94160507627175939-127.0.0.1:49602_-n_0000000000) 
[n:127.0.0.1:49602_    ] o.a.s.c.OverseerCollectionProcessor Process current 
queue of collection creations
   [junit4]   2> 2841898 INFO  
(OverseerStateUpdate-94160507627175939-127.0.0.1:49602_-n_0000000000) 
[n:127.0.0.1:49602_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 2841902 INFO  (TEST-Hdf

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

.metrics2.util.MBeans.unregister(MBeans.java:81)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197)
   [junit4]   2>        at 
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50)
   [junit4]   2>        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native 
Method)
   [junit4]   2>        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:483)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2>        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2>        at 
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2>        ... 53 more
   [junit4]   2> 4168651 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[34491A4993AAC335]-worker) 
[n:127.0.0.1:57166_ c:addReplicaColl s:shard2 r:core_node6 
x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jul 13, 2015 4:37:31 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): 
{other_tl1=FSTOrd50, range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), 
_version_=FSTOrd50, multiDefault=Lucene50(blocksize=128), a_t=FSTOrd50, 
intDefault=FSTOrd50, a_i1=FSTOrd50, id=PostingsFormat(name=LuceneFixedGap), 
range_facet_i_dv=FSTOrd50, text=PostingsFormat(name=LuceneVarGapFixedInterval), 
range_facet_l=FSTOrd50, timestamp=FSTOrd50}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), 
range_facet_i_dv=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=nl_BE, 
timezone=Africa/Libreville
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.8.0_25 (64-bit)/cpus=4,threads=2,free=176789488,total=531628032
   [junit4]   2> NOTE: All tests run in this JVM: [DebugComponentTest, 
TestSchemaResource, ChangedSchemaMergeTest, TestDocBasedVersionConstraints, 
TestSchemaSimilarityResource, BlockDirectoryTest, 
TestWordDelimiterFilterFactory, TestUniqueKeyFieldResource, 
DeleteInactiveReplicaTest, SignatureUpdateProcessorFactoryTest, 
MergeStrategyTest, TestPivotHelperCode, TestRebalanceLeaders, 
TestNonDefinedSimilarityFactory, TestJsonFacets, TestStressLucene, 
TestNamedUpdateProcessors, TestFreeTextSuggestions, CSVRequestHandlerTest, 
ZkStateWriterTest, AssignTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
BJQParserTest, TestRandomDVFaceting, SharedFSAutoReplicaFailoverTest, 
TestSchemaManager, TermsComponentTest, ExternalFileFieldSortTest, 
TestFileDictionaryLookup, TestInfoStreamLogging, TestRTimer, ZkNodePropsTest, 
ExitableDirectoryReaderTest, DistributedFacetPivotLongTailTest, 
DistributedFacetPivotWhiteBoxTest, SuggesterFSTTest, 
HdfsUnloadDistributedZkTest, TestImplicitCoreProperties, SyncSliceTest, 
TestConfig, DocValuesMissingTest, DateMathParserTest, TestBadConfig, 
TestSimpleTrackingShardHandler, TestRequestStatusCollectionAPI, 
TestConfigReload, FastVectorHighlighterTest, DeleteShardTest, 
TestReloadAndDeleteDocs, CurrencyFieldXmlFileTest, 
ChaosMonkeyNothingIsSafeTest, DistribDocExpirationUpdateProcessorTest, 
IndexSchemaTest, TestShardHandlerFactory, DefaultValueUpdateProcessorTest, 
ConnectionManagerTest, TestTrackingShardHandlerFactory, 
TestHighlightDedupGrouping, SegmentsInfoRequestHandlerTest, 
TestIntervalFaceting, HighlighterTest, TestCollectionAPI, RequestHandlersTest, 
QueryElevationComponentTest, OverseerRolesTest, 
TestReversedWildcardFilterFactory, TestManagedSchemaFieldTypeResource, 
TestMergePolicyConfig, TestBlendedInfixSuggestions, TestLazyCores, 
TestRangeQuery, SolrRequestParserTest, SharedFSAutoReplicaFailoverUtilsTest, 
TestMacros, TestRealTimeGet, TestCloudInspectUtil, 
CollectionTooManyReplicasTest, MinimalSchemaTest, TestFieldResource, 
TestInitQParser, TestBinaryField, TestTrieFacet, 
CollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, 
ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, 
TestReplicationHandler, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, 
TestTolerantSearch, TestEmbeddedSolrServerConstructors, AliasIntegrationTest, 
CdcrVersionReplicationTest, CleanupOldIndexTest, CustomCollectionTest, 
LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverriddenZkACLAndCredentialsProvidersTest, RecoveryAfterSoftCommitTest, 
ReplicationFactorTest, SimpleCollectionCreateDeleteTest, TestCloudPivotFacet, 
TestMiniSolrCloudClusterSSL, TestRandomRequestDistribution, 
TestSolrCloudWithKerberosAlt, TriLevelCompositeIdRoutingTest, 
HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [515/515] on J0 in 1339.51s, 1 test, 1 error <<< FAILURES!

[...truncated 12 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981:
 There were test failures: 515 suites, 2025 tests, 2 errors, 20 ignored (10 
assumptions)

Total time: 178 minutes 20 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to